Attachment 108601 Details for Bug 79765 – master log playing letters debug osd showing 35fps

master log playing letters debug osd showing 35fps

kodi-letters-35fps-log (text/plain), 74.01 KB, created by Andy Furniss on 2014-10-29 00:42:25 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Andy Furniss

Created: 2014-10-29 00:42:25 UTC

Size: 74.01 KB

patch

obsolete

>ï»¿18:12:18 T:140681768351744  NOTICE: special://profile/ is mapped to: special://masterprofile/
>18:12:18 T:140681768351744  NOTICE: -----------------------------------------------------------------------
>18:12:18 T:140681768351744  NOTICE: Starting Kodi (14.0-ALPHA5 Git:2014-10-28-cfd0aa1-dirty). Platform: Linux x86 64-bit
>18:12:18 T:140681768351744  NOTICE: Using Debug Kodi x64 build
>18:12:18 T:140681768351744  NOTICE: Kodi compiled Oct 28 2014 by GCC 4.9.1 for Linux x86 64-bit version 3.17.0 (200960)
>18:12:18 T:140681768351744  NOTICE: Running on Unknown Linux Distribution 0.0, kernel: Linux x86 64-bit version 3.18.0-rc1-10539-g6f628cb
>18:12:18 T:140681768351744  NOTICE: FFmpeg statically linked, version: 14.0a4-Helix-458-gcfd0aa1-xbmc-2.4.2-Helix-alpha5
>18:12:18 T:140681768351744  NOTICE: Host CPU: Intel(R) Celeron(R) CPU J1900 @ 1.99GHz, 4 cores available
>18:12:18 T:140681768351744  NOTICE: special://xbmc/ is mapped to: /home/andy/Src/xbmc
>18:12:18 T:140681768351744  NOTICE: special://xbmcbin/ is mapped to: /home/andy/Src/xbmc
>18:12:18 T:140681768351744  NOTICE: special://masterprofile/ is mapped to: /home/andy/.kodi/userdata
>18:12:18 T:140681768351744  NOTICE: special://home/ is mapped to: /home/andy/.kodi
>18:12:18 T:140681768351744  NOTICE: special://temp/ is mapped to: /home/andy/.kodi/temp
>18:12:18 T:140681768351744  NOTICE: The executable running is: /home/andy/Src/xbmc/kodi.bin
>18:12:18 T:140681768351744  NOTICE: Local hostname: asr
>18:12:18 T:140681768351744  NOTICE: Log File is located: /home/andy/.kodi/temp/kodi.log
>18:12:18 T:140681768351744  NOTICE: -----------------------------------------------------------------------
>18:12:18 T:140681768351744  NOTICE: load settings...
>18:12:18 T:140681768351744   DEBUG: CSettings: loaded settings definition from special://xbmc/system/settings/settings.xml
>18:12:18 T:140681768351744 WARNING: CSettingInt: unknown options filler "audiocdactions" of "audiocds.autoaction"
>18:12:18 T:140681768351744   DEBUG: CSettings: loaded settings definition from special://xbmc/system/settings/linux.xml
>18:12:18 T:140681768351744   DEBUG: CAESinkALSA - HDMI device "hdmi:CARD=PCH,DEV=1" may be unconnected (no ELD data)
>18:12:18 T:140681768351744  NOTICE: Found 1 Lists of Devices
>18:12:18 T:140681768351744  NOTICE: Enumerated ALSA devices:
>18:12:18 T:140681768351744  NOTICE:     Device 1
>18:12:18 T:140681768351744  NOTICE:         m_deviceName      : @
>18:12:18 T:140681768351744  NOTICE:         m_displayName     : Default (HDA Intel PCH ALC892 Analog)
>18:12:18 T:140681768351744  NOTICE:         m_displayNameExtra:
>18:12:18 T:140681768351744  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
>18:12:18 T:140681768351744  NOTICE:         m_channels        : FL,FR,BL,BR,FC,LFE,SL,SR
>18:12:18 T:140681768351744  NOTICE:         m_sampleRates     : 48000
>18:12:18 T:140681768351744  NOTICE:         m_dataFormats     : AE_FMT_S32NE
>18:12:18 T:140681768351744  NOTICE:     Device 2
>18:12:18 T:140681768351744  NOTICE:         m_deviceName      : @:CARD=PCH,DEV=0
>18:12:18 T:140681768351744  NOTICE:         m_displayName     : HDA Intel PCH (@:CARD=PCH,DEV=0)
>18:12:18 T:140681768351744  NOTICE:         m_displayNameExtra: ALC892 Analog
>18:12:18 T:140681768351744  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
>18:12:18 T:140681768351744  NOTICE:         m_channels        : FL,FR,BL,BR,FC,LFE,SL,SR
>18:12:18 T:140681768351744  NOTICE:         m_sampleRates     : 48000
>18:12:18 T:140681768351744  NOTICE:         m_dataFormats     : AE_FMT_S32NE
>18:12:18 T:140681768351744  NOTICE:     Device 3
>18:12:18 T:140681768351744  NOTICE:         m_deviceName      : surround21:CARD=PCH,DEV=0
>18:12:18 T:140681768351744  NOTICE:         m_displayName     : HDA Intel PCH (surround21:CARD=PCH,DEV=0)
>18:12:18 T:140681768351744  NOTICE:         m_displayNameExtra: ALC892 Analog
>18:12:18 T:140681768351744  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
>18:12:18 T:140681768351744  NOTICE:         m_channels        : FL,FR,BL,BR,FC,LFE,SL,SR,UNKNOWN1,UNKNOWN2,UNKNOWN3,UNKNOWN4,UNKNOWN5,UNKNOWN6,UNKNOWN7,UNKNOWN8
>18:12:18 T:140681768351744  NOTICE:         m_sampleRates     : 44100,48000,96000,192000
>18:12:18 T:140681768351744  NOTICE:         m_dataFormats     : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE
>18:12:18 T:140681768351744  NOTICE:     Device 4
>18:12:18 T:140681768351744  NOTICE:         m_deviceName      : iec958:CARD=PCH,DEV=0
>18:12:18 T:140681768351744  NOTICE:         m_displayName     : HDA Intel PCH
>18:12:18 T:140681768351744  NOTICE:         m_displayNameExtra: ALC892 Digital S/PDIF
>18:12:18 T:140681768351744  NOTICE:         m_deviceType      : AE_DEVTYPE_IEC958
>18:12:18 T:140681768351744  NOTICE:         m_channels        : FL,FR
>18:12:18 T:140681768351744  NOTICE:         m_sampleRates     : 32000,44100,48000,88200,96000,192000
>18:12:18 T:140681768351744  NOTICE:         m_dataFormats     : AE_FMT_AC3,AE_FMT_DTS,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE
>18:12:18 T:140681768351744  NOTICE:     Device 5
>18:12:18 T:140681768351744  NOTICE:         m_deviceName      : hdmi:CARD=PCH,DEV=0
>18:12:18 T:140681768351744  NOTICE:         m_displayName     : HDA Intel PCH
>18:12:18 T:140681768351744  NOTICE:         m_displayNameExtra: MEI Panasonic-TV on HDMI
>18:12:18 T:140681768351744  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI
>18:12:18 T:140681768351744  NOTICE:         m_channels        : FL,FR,LFE,FC,BC,BL,BR,FLOC,FROC,BLOC,BROC,TFC,TC,TFL,TFR,UNKNOWN1
>18:12:18 T:140681768351744  NOTICE:         m_sampleRates     : 32000,44100,48000
>18:12:18 T:140681768351744  NOTICE:         m_dataFormats     : AE_FMT_LPCM,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_DTSHD,AE_FMT_TRUEHD,AE_FMT_EAC3,AE_FMT_DTS,AE_FMT_AC3,AE_FMT_AAC
>18:12:18 T:140681768351744  NOTICE:     Device 6
>18:12:18 T:140681768351744  NOTICE:         m_deviceName      : hdmi:CARD=PCH,DEV=1
>18:12:18 T:140681768351744  NOTICE:         m_displayName     : HDA Intel PCH
>18:12:18 T:140681768351744  NOTICE:         m_displayNameExtra: HDMI
>18:12:18 T:140681768351744  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI
>18:12:18 T:140681768351744  NOTICE:         m_channels        : FL,FR,LFE,FC,BC,BL,BR,FLOC,FROC,BLOC,BROC,TFC,TC,TFL,TFR,UNKNOWN1
>18:12:18 T:140681768351744  NOTICE:         m_sampleRates     : 32000,44100,48000,88200,96000,176400,192000
>18:12:18 T:140681768351744  NOTICE:         m_dataFormats     : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_LPCM,AE_FMT_DTSHD,AE_FMT_TRUEHD,AE_FMT_EAC3,AE_FMT_DTS,AE_FMT_AC3,AE_FMT_AAC
>18:12:18 T:140681768351744  NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml)
>18:12:18 T:140681768351744  NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
>18:12:18 T:140681768351744  NOTICE: Default DVD Player: dvdplayer
>18:12:18 T:140681768351744  NOTICE: Default Video Player: dvdplayer
>18:12:18 T:140681768351744  NOTICE: Default Audio Player: paplayer
>18:12:18 T:140681768351744  NOTICE: Enabled debug logging due to GUI setting (2)
>18:12:18 T:140681768351744  NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM"
>18:12:18 T:140681768351744  NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
>18:12:18 T:140681768351744  NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
>18:12:18 T:140681768351744   DEBUG: CPlayerCoreConfig::<ctor>: created player DVDPlayer for core 1
>18:12:18 T:140681768351744   DEBUG: CPlayerCoreConfig::<ctor>: created player oldmplayercore for core 1
>18:12:18 T:140681768351744   DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer for core 3
>18:12:18 T:140681768351744   DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
>18:12:18 T:140681768351744   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtv
>18:12:18 T:140681768351744   DEBUG: CPlayerSelectionRule::Initialize: creating rule: hdhomerun/myth/mms/udp
>18:12:18 T:140681768351744   DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
>18:12:18 T:140681768351744   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
>18:12:18 T:140681768351744   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
>18:12:18 T:140681768351744   DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
>18:12:18 T:140681768351744   DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
>18:12:18 T:140681768351744   DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
>18:12:18 T:140681768351744   DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
>18:12:18 T:140681768351744   DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
>18:12:18 T:140681768351744   DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
>18:12:18 T:140681768351744   DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
>18:12:18 T:140681768351744   DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
>18:12:18 T:140681768351744  NOTICE: Loaded playercorefactory configuration
>18:12:18 T:140681768351744  NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
>18:12:18 T:140681768351744  NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
>18:12:18 T:140681768351744    INFO: creating subdirectories
>18:12:18 T:140681768351744    INFO: userdata folder: special://masterprofile/
>18:12:18 T:140681768351744    INFO: recording folder:
>18:12:18 T:140681768351744    INFO: screenshots folder:
>18:12:18 T:140681768351744    INFO: load language info file: special://xbmc/language/English/langinfo.xml
>18:12:18 T:140681768351744   DEBUG: trying to set locale to en_US.UTF-8
>18:12:18 T:140681768351744    INFO: global locale set to en_US.UTF-8
>18:12:18 T:140681768351744    INFO: load English language file, from path: special://xbmc/language/
>18:12:18 T:140681768351744   DEBUG: POParser: loaded 3261 strings from file /home/andy/Src/xbmc/language/English/strings.po
>18:12:18 T:140681566447360  NOTICE: Thread ActiveAE start, auto delete: false
>18:12:18 T:140681558054656  NOTICE: Thread AESink start, auto delete: false
>18:12:18 T:140681558054656    INFO: CActiveAESink::OpenSink - initialize sink
>18:12:18 T:140681558054656   DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@
>18:12:18 T:140681558054656    INFO: CAESinkALSA::Initialize - Attempting to open device "@"
>18:12:18 T:140681558054656    INFO: CAESinkALSA::Initialize - Opened device "sysdefault"
>18:12:18 T:140681558054656    INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
>18:12:18 T:140681558054656    INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
>18:12:18 T:140681558054656   DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 1024, bufferSize 8192
>18:12:18 T:140681558054656   DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 1024, bufferSize 8192
>18:12:18 T:140681558054656   DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 171 ms
>18:12:18 T:140681558054656   DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
>18:12:18 T:140681558054656   DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
>18:12:18 T:140681558054656   DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)
>18:12:18 T:140681558054656   DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
>18:12:18 T:140681558054656   DEBUG:   Output Device : Default (HDA Intel PCH ALC892 Analog)
>18:12:18 T:140681558054656   DEBUG:   Sample Rate   : 48000
>18:12:18 T:140681558054656   DEBUG:   Sample Format : AE_FMT_S32NE
>18:12:18 T:140681558054656   DEBUG:   Channel Count : 2
>18:12:18 T:140681558054656   DEBUG:   Channel Layout: FL,FR
>18:12:18 T:140681558054656   DEBUG:   Frames        : 1024
>18:12:18 T:140681558054656   DEBUG:   Frame Samples : 2048
>18:12:18 T:140681558054656   DEBUG:   Frame Size    : 8
>18:12:18 T:140681768351744  NOTICE: Running database version Addons16
>18:12:18 T:140681768351744   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-x86_64-linux.so)
>18:12:18 T:140681768351744   DEBUG: Loading: /home/andy/Src/xbmc/system/libcpluff-x86_64-linux.so
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in visualization.fishbmc has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in visualization.vortex has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.euphoria has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in repository.pvr-osx32.xbmc.org has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.wma has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in visualization.projectm has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in repository.pvr-ios.xbmc.org has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in service.xbmc.versioncheck has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in skin.confluence has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in visualization.glspectrum has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in visualization.milkdrop has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.amazon.de has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in visualization.dxspectrum has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.musicvideos.theaudiodb.com has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.solarwinds has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in repository.pvr-android.xbmc.org has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.aac has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in repository.pvr-win32.xbmc.org has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in repository.pvr-osx64.xbmc.org has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.last.fm has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in visualization.waveform has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.plasma has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.hdtrailers.net has been installed.'
>18:12:18 T:140681768351744   DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
>18:12:18 T:140681768351744  NOTICE: ADDONS: Using repository repository.xbmc.org
>18:12:18 T:140681768351744    INFO: LIRC Initialize: using: /dev/lircd
>18:12:18 T:140681768351744    INFO: LIRC Initialize: connect failed: No such file or directory
>18:12:18 T:140681768351744   DEBUG: Failed to connect to LIRC. Retry in 10s.
>18:12:18 T:140681768351744   DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
>18:12:18 T:140681768351744   DEBUG: LoadMappings - loaded node "CEC Adapter"
>18:12:18 T:140681768351744   DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
>18:12:18 T:140681768351744   DEBUG: LoadMappings - loaded node "iMON HID device"
>18:12:18 T:140681768351744   DEBUG: CPeripheralBusUSB - initialised udev monitor
>18:12:18 T:140681547486976  NOTICE: Thread PeripBusUSBUdev start, auto delete: false
>18:12:18 T:140681768351744   DEBUG: Selected UDev as storage provider
>18:12:19 T:140681768351744    INFO: Available videomodes (xrandr):
>18:12:19 T:140681768351744    INFO: Output 'HDMI1' has 19 modes
>18:12:19 T:140681768351744    INFO: ID:0x49 Name:1920x1080 Refresh:50.000000 Width:1920 Height:1080
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.001594
>18:12:19 T:140681768351744    INFO: ID:0xad Name:1920x1080 Refresh:60.000000 Width:1920 Height:1080
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.001594
>18:12:19 T:140681768351744    INFO: ID:0xae Name:1920x1080 Refresh:59.940201 Width:1920 Height:1080
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.001594
>18:12:19 T:140681768351744    INFO: ID:0xb1 Name:1920x1080 Refresh:24.000000 Width:1920 Height:1080
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.001594
>18:12:19 T:140681768351744    INFO: ID:0xb3 Name:1920x1080 Refresh:23.976080 Width:1920 Height:1080
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.001594
>18:12:19 T:140681768351744    INFO: ID:0xaf Name:1920x1080i Refresh:30.000000 Width:1920 Height:1080
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.001594
>18:12:19 T:140681768351744    INFO: ID:0xb0 Name:1920x1080i Refresh:25.000000 Width:1920 Height:1080
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.001594
>18:12:19 T:140681768351744    INFO: ID:0xb2 Name:1920x1080i Refresh:29.970100 Width:1920 Height:1080
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.001594
>18:12:19 T:140681768351744    INFO: ID:0xb4 Name:1280x720 Refresh:60.000000 Width:1280 Height:720
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.001594
>18:12:19 T:140681768351744    INFO: ID:0xb5 Name:1280x720 Refresh:50.000000 Width:1280 Height:720
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.001594
>18:12:19 T:140681768351744    INFO: ID:0xb6 Name:1280x720 Refresh:59.940201 Width:1280 Height:720
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.001594
>18:12:19 T:140681768351744    INFO: ID:0xb7 Name:720x576 Refresh:50.000000 Width:720 Height:576
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.424490
>18:12:19 T:140681768351744    INFO: ID:0xb8 Name:720x576i Refresh:25.000000 Width:720 Height:576
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.424490
>18:12:19 T:140681768351744    INFO: ID:0xb9 Name:720x480 Refresh:60.000000 Width:720 Height:480
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.187075
>18:12:19 T:140681768351744    INFO: ID:0xba Name:720x480 Refresh:59.940060 Width:720 Height:480
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.187075
>18:12:19 T:140681768351744    INFO: ID:0xbb Name:720x480i Refresh:29.998890 Width:720 Height:480
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.187075
>18:12:19 T:140681768351744    INFO: ID:0xbc Name:720x480i Refresh:29.970030 Width:720 Height:480
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.187075
>18:12:19 T:140681768351744    INFO: ID:0xbd Name:640x480 Refresh:60.000000 Width:640 Height:480
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.335459
>18:12:19 T:140681768351744    INFO: ID:0xbe Name:640x480 Refresh:59.940479 Width:640 Height:480
>18:12:19 T:140681768351744    INFO: Pixel Ratio: 1.335459
>18:12:19 T:140681768351744  NOTICE: Checking resolution 16
>18:12:19 T:140681768351744   DEBUG: Window Manager Name: Fluxbox
>18:12:19 T:140681768351744   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-x86_64-linux.so)
>18:12:19 T:140681768351744   DEBUG: Loading: /home/andy/Src/xbmc/system/ImageLib-x86_64-linux.so
>18:12:19 T:140681768351744  NOTICE: Using visual 0x20
>18:12:19 T:140681768351744    INFO: GL: Maximum texture width: 8192
>18:12:19 T:140681768351744   DEBUG: GLX_EXTENSIONS: GLX_ARB_create_context GLX_ARB_create_context_profile GLX_ARB_create_context_robustness GLX_ARB_fbconfig_float GLX_ARB_framebuffer_sRGB GLX_ARB_get_proc_address GLX_ARB_multisample GLX_EXT_import_context GLX_EXT_visual_info GLX_EXT_visual_rating GLX_EXT_framebuffer_sRGB GLX_EXT_create_context_es2_profile GLX_MESA_copy_sub_buffer GLX_MESA_multithread_makecurrent GLX_MESA_query_renderer 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 GLX_EXT_buffer_age
>18:12:19 T:140681768351744  NOTICE: GL_VENDOR = Intel Open Source Technology Center
>18:12:19 T:140681768351744  NOTICE: GL_RENDERER = Mesa DRI Intel(R) Bay Trail
>18:12:19 T:140681768351744  NOTICE: GL_VERSION = 3.0 Mesa 10.4.0-devel (git-3b83a5c)
>18:12:19 T:140681768351744  NOTICE: GL_SHADING_LANGUAGE_VERSION = 1.30
>18:12:19 T:140681768351744  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_3DFX_texture_compression_FXT1 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_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_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_EXT_packed_float GL_EXT_pixel_buffer_object GL_EXT_texture_compression_dxt1 GL_EXT_texture_compression_rgtc 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_APPLE_object_purgeable GL_ARB_vertex_array_object GL_ATI_separate_stencil GL_EXT_draw_buffers2 GL_EXT_draw_instanced GL_EXT_gpu_program_parameters GL_EXT_texture_array GL_EXT_texture_integer GL_EXT_texture_sRGB_decode GL_EXT_timer_query GL_OES_EGL_image GL_AMD_performance_monitor 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_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_sample_shading GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_shader_texture_lod GL_ARB_texture_cube_map_array GL_ARB_texture_gather GL_ARB_texture_multisample GL_ARB_texture_query_lod 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_ARB_get_program_binary GL_ARB_robustness GL_ARB_separate_shader_objects GL_ARB_shader_bit_encoding GL_ARB_texture_compression_bptc GL_ARB_timer_query GL_ARB_transform_feedback2 GL_ARB_transform_feedback3 GL_ANGLE_texture_compression_dxt3 GL_ANGLE_texture_compression_dxt5 GL_ARB_compressed_texture_pixel_storage GL_ARB_conservative_depth GL_ARB_internalformat_query GL_ARB_map_buffer_alignment GL_ARB_shader_atomic_counters 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_AMD_shader_trinary_minmax GL_ARB_ES3_compatibility GL_ARB_clear_buffer_object GL_ARB_copy_image GL_ARB_explicit_uniform_location GL_ARB_invalidate_subdata GL_ARB_texture_query_levels GL_ARB_texture_storage_multisample GL_ARB_texture_view GL_ARB_vertex_attrib_binding GL_KHR_debug GL_ARB_buffer_storage GL_ARB_clear_texture GL_ARB_multi_bind GL_ARB_seamless_cubemap_per_texture GL_ARB_texture_mirror_clamp_to_edge GL_ARB_vertex_type_10f_11f_11f_rev GL_EXT_shader_integer_mix GL_INTEL_performance_query GL_ARB_conditional_render_inverted GL_ARB_derivative_control GL_KHR_context_flush_control
>18:12:19 T:140681768351744    INFO: GL: Maximum texture width: 8192
>18:12:19 T:140681768351744   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
>18:12:19 T:140681768351744    INFO: load default splash image: /home/andy/Src/xbmc/media/Splash.png
>18:12:19 T:140681768351744    INFO: load keymapping
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/keymaps/appcommand.xml
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/keymaps/gamepad.xml
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/keymaps/joystick.Alienware.Dual.Compatible.Controller.xml
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/keymaps/joystick.Harmony.xml
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/keymaps/joystick.Interact.AxisPad.xml
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.360.Controller.xml
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/keymaps/joystick.Nintendo.Wii.U.Pro.Controller.xml
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/keymaps/joystick.PS3.Remote.Keyboard.xml
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/keymaps/keyboard.xml
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/keymaps/mouse.xml
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/keymaps/remote.xml
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/keymaps/touchscreen.xml
>18:12:19 T:140681768351744    INFO: Loading special://xbmc/system/Lircmap.xml
>18:12:19 T:140681768351744    INFO: * Adding remote mapping for device 'mceusb'
>18:12:19 T:140681768351744    INFO: * Adding remote mapping for device 'XboxDVDDongle'
>18:12:19 T:140681768351744    INFO: * Adding remote mapping for device 'Microsoft_Xbox'
>18:12:19 T:140681768351744    INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
>18:12:19 T:140681768351744    INFO: * Adding remote mapping for device 'anysee'
>18:12:19 T:140681768351744    INFO: * Adding remote mapping for device 'iMON-PAD'
>18:12:19 T:140681768351744    INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
>18:12:19 T:140681768351744    INFO: * Adding remote mapping for device 'MCE_via_iMON'
>18:12:19 T:140681768351744    INFO: * Adding remote mapping for device 'TwinHanRemote'
>18:12:19 T:140681768351744    INFO: * Adding remote mapping for device 'linux-input-layer'
>18:12:19 T:140681768351744    INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
>18:12:19 T:140681768351744    INFO: * Adding remote mapping for device 'mediacenter'
>18:12:19 T:140681768351744    INFO: * Adding remote mapping for device 'devinput'
>18:12:19 T:140681768351744   DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
>18:12:19 T:140681768351744    INFO: GUI format 1920x1080, Display 1920x1080@ 50.00 - Full Screen
>18:12:19 T:140681768351744   DEBUG: guilib: Fill viewport on change for solving rendering passes
>18:12:19 T:140681768351744   DEBUG: SECTION:LoadDLL(libcurl.so.4)
>18:12:19 T:140681768351744   DEBUG: Loading: libcurl.so.4
>18:12:19 T:140681768351744  NOTICE: Running database version Addons16
>18:12:19 T:140681768351744   DEBUG: Initialize, updating databases...
>18:12:19 T:140681768351744  NOTICE: Running database version ViewModes6
>18:12:19 T:140681768351744  NOTICE: Running database version Textures13
>18:12:19 T:140681768351744  NOTICE: Running database version MyMusic48
>18:12:19 T:140681768351744  NOTICE: Running database version MyVideos90
>18:12:19 T:140681768351744  NOTICE: Running database version TV26
>18:12:19 T:140681768351744  NOTICE: Running database version Epg8
>18:12:19 T:140681768351744   DEBUG: Initialize, updating databases... DONE
>18:12:19 T:140681768351744   DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
>18:12:19 T:140681768351744   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
>18:12:19 T:140681768351744   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
>18:12:19 T:140681768351744    INFO: Unloading old skin ...
>18:12:19 T:140681768351744    INFO:   load skin from: /home/andy/Src/xbmc/addons/skin.confluence (version: 2.5.3)
>18:12:19 T:140681768351744    INFO:   load fonts for skin...
>18:12:19 T:140681768351744    INFO: Loading fonts from /home/andy/Src/xbmc/addons/skin.confluence/720p/Font.xml
>18:12:19 T:140681768351744   DEBUG: POParser: loaded 149 strings from file /home/andy/Src/xbmc/addons/skin.confluence/language/English/strings.po
>18:12:19 T:140681768351744    INFO: Loading skin includes from /home/andy/Src/xbmc/addons/skin.confluence/720p/includes.xml
>18:12:19 T:140681768351744    INFO:   load new skin...
>18:12:19 T:140681768351744    INFO: Loading user windows, path /home/andy/Src/xbmc/addons/skin.confluence/720p
>18:12:19 T:140681768351744   DEBUG: Load Skin XML: 3.24ms
>18:12:19 T:140681768351744    INFO:   initialize new skin...
>18:12:19 T:140681768351744   DEBUG: guilib: Fill viewport on change for solving rendering passes
>18:12:19 T:140681768351744    INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
>18:12:19 T:140681768351744   DEBUG: Load Pointer.xml: 0.91ms
>18:12:19 T:140681768351744   DEBUG: Alloc resources: 4.94ms  (0.96 ms skin load)
>18:12:19 T:140681768351744    INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
>18:12:19 T:140681768351744   DEBUG: Load DialogVolumeBar.xml: 3.00ms
>18:12:19 T:140681768351744   DEBUG: Alloc resources: 4.90ms  (3.06 ms skin load)
>18:12:19 T:140681768351744    INFO: Loading skin file: DialogKaiToast.xml, load type: LOAD_ON_GUI_INIT
>18:12:19 T:140681768351744   DEBUG: Load DialogKaiToast.xml: 2.02ms
>18:12:19 T:140681768351744   DEBUG: Alloc resources: 2.08ms  (2.08 ms skin load)
>18:12:19 T:140681768351744    INFO: Loading skin file: DialogMuteBug.xml, load type: LOAD_ON_GUI_INIT
>18:12:19 T:140681768351744   DEBUG: Load DialogMuteBug.xml: 0.44ms
>18:12:19 T:140681768351744   DEBUG: Alloc resources: 0.47ms  (0.47 ms skin load)
>18:12:19 T:140681768351744    INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
>18:12:19 T:140681768351744   DEBUG: Load DialogSeekBar.xml: 7.13ms
>18:12:19 T:140681768351744   DEBUG: Alloc resources: 9.96ms  (7.23 ms skin load)
>18:12:19 T:140681768351744    INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
>18:12:19 T:140681768351744   DEBUG: Load DialogBusy.xml: 1.25ms
>18:12:19 T:140681768351744   DEBUG: Alloc resources: 1.31ms  (1.30 ms skin load)
>18:12:19 T:140681768351744    INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
>18:12:19 T:140681768351744   DEBUG: Load DialogExtendedProgressBar.xml: 1.27ms
>18:12:19 T:140681768351744   DEBUG: Alloc resources: 1.32ms  (1.31 ms skin load)
>18:12:19 T:140681768351744    INFO: Loading /home/andy/Src/xbmc/addons/skin.confluence/sounds/sounds.xml
>18:12:19 T:140681768351744    INFO:   skin loaded...
>18:12:19 T:140681768351744   DEBUG: JSONRPC: JSON schema type broadcastnext references an unknown type PVR.Details.Broadcast
>18:12:19 T:140681768351744 WARNING: JSONRPC: Could not parse type "PVR.Details.Channel"
>18:12:19 T:140681768351744    INFO: JSONRPC: Adding type "PVR.Details.Channel" to list of incomplete definitions (waiting for "PVR.Details.Broadcast")
>18:12:19 T:140681768351744   DEBUG: JSONRPC: JSON schema type  references an unknown type PVR.Details.Channel
>18:12:19 T:140681768351744   DEBUG: Invalid item definition in "items" for type channels
>18:12:19 T:140681768351744 WARNING: JSONRPC: Could not parse type "PVR.Details.ChannelGroup.Extended"
>18:12:19 T:140681768351744    INFO: JSONRPC: Adding type "PVR.Details.ChannelGroup.Extended" to list of incomplete definitions (waiting for "PVR.Details.Channel")
>18:12:19 T:140681768351744    INFO: JSONRPC: Resolving incomplete types/methods referencing PVR.Details.Broadcast
>18:12:19 T:140681768351744    INFO: JSONRPC: Resolving incomplete types/methods referencing PVR.Details.Channel
>18:12:19 T:140681768351744   DEBUG: JSONRPC: JSON schema type stereoscopicmode references an unknown type GUI.Stereoscopy.Mode
>18:12:19 T:140681768351744 WARNING: JSONRPC: Could not parse type "GUI.Property.Value"
>18:12:19 T:140681768351744    INFO: JSONRPC: Adding type "GUI.Property.Value" to list of incomplete definitions (waiting for "GUI.Stereoscopy.Mode")
>18:12:19 T:140681768351744    INFO: JSONRPC: Resolving incomplete types/methods referencing GUI.Stereoscopy.Mode
>18:12:19 T:140681768351744   DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
>18:12:19 T:140681768351744 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
>18:12:19 T:140681768351744    INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
>18:12:19 T:140681768351744    INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
>18:12:19 T:140681768351744    INFO: JSONRPC v6.20.0: Successfully initialized
>18:12:19 T:140681768351744   DEBUG: ADDON: Starting service addons.
>18:12:19 T:140681768351744   DEBUG: Activating window ID: 12999
>18:12:19 T:140681768351744   DEBUG: ------ Window Init (Startup.xml) ------
>18:12:19 T:140681768351744    INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
>18:12:19 T:140681294173952  NOTICE: Thread LanguageInvoker start, auto delete: false
>18:12:19 T:140681294173952    INFO: initializing python engine.
>18:12:19 T:140681768351744   DEBUG: Load Startup.xml: 2.53ms
>18:12:19 T:140681768351744   DEBUG: Alloc resources: 2.58ms  (2.58 ms skin load)
>18:12:19 T:140681768351744    INFO: removing tempfiles
>18:12:19 T:140681768351744   DEBUG: ADDON: Starting service addons.
>18:12:19 T:140681768351744  NOTICE: initialize done
>18:12:19 T:140681768351744  NOTICE: Running the application...
>18:12:19 T:140681768351744   DEBUG: Activating window ID: 10000
>18:12:19 T:140681294173952   DEBUG: CPythonInvoker(0, /home/andy/.kodi/addons/service.xbmc.versioncheck/service.py): start processing
>18:12:19 T:140681294173952  NOTICE: -->Python Interpreter Initialized<--
>18:12:19 T:140681294173952   DEBUG: CPythonInvoker(0, /home/andy/.kodi/addons/service.xbmc.versioncheck/service.py): the source file to load is "/home/andy/.kodi/addons/service.xbmc.versioncheck/service.py"
>18:12:19 T:140681294173952   DEBUG: CPythonInvoker(0, /home/andy/.kodi/addons/service.xbmc.versioncheck/service.py): setting the Python path to /home/andy/.kodi/addons/service.xbmc.versioncheck:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages
>18:12:19 T:140681294173952   DEBUG: CPythonInvoker(0, /home/andy/.kodi/addons/service.xbmc.versioncheck/service.py): entering source directory /home/andy/.kodi/addons/service.xbmc.versioncheck
>18:12:19 T:140681294173952   DEBUG: CPythonInvoker(0, /home/andy/.kodi/addons/service.xbmc.versioncheck/service.py): instantiating addon using automatically obtained id of "service.xbmc.versioncheck" dependent on version 2.1.0 of the xbmc.python api
>18:12:19 T:140681768351744   DEBUG: ------ Window Init () ------
>18:12:19 T:140681768351744   DEBUG: Alloc resources: 0.00ms  (0.00 ms skin load)
>18:12:19 T:140681294173952   DEBUG: XBMC Version Check: Version 0.1.23 started
>18:12:20 T:140681768351744   DEBUG: ------ Window Deinit (Startup.xml) ------
>18:12:20 T:140681768351744   DEBUG: ------ Window Init (Home.xml) ------
>18:12:20 T:140681768351744    INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
>18:12:20 T:140681768351744   DEBUG: Load Home.xml: 69.88ms
>18:12:20 T:140681768351744   DEBUG: Alloc resources: 108.33ms  (70.24 ms skin load)
>18:12:20 T:140681264449280  NOTICE: Thread JobWorker start, auto delete: true
>18:12:20 T:140681264449280   DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
>18:12:20 T:140681264449280   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
>18:12:20 T:140681264449280   DEBUG: GetRecentlyAddedAlbums query: select * from albumview where strAlbum != '' order by idAlbum desc limit 10
>18:12:20 T:140681264449280   DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
>18:12:20 T:140681768351744   DEBUG: no profile autoexec.py (/home/andy/.kodi/userdata/autoexec.py) found, skipping
>18:12:20 T:140681768351744   DEBUG: NetworkMessage - Starting network services
>18:12:20 T:140681768351744  NOTICE: ES: Starting event server
>18:12:20 T:140681294173952    INFO: CPythonInvoker(0, /home/andy/.kodi/addons/service.xbmc.versioncheck/service.py): script successfully run
>18:12:20 T:140681184016128  NOTICE: Thread EventServer start, auto delete: false
>18:12:20 T:140681184016128  NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
>18:12:20 T:140681184016128  NOTICE: UDP: Listening on port 9777
>18:12:20 T:140681768351744    INFO: JSONRPC Server: Successfully initialized
>18:12:20 T:140681768351744   DEBUG: started alarm with name: checkatboot
>18:12:20 T:140681167230720  NOTICE: Thread AlarmClock start, auto delete: false
>18:12:20 T:140681768351744   DEBUG: started alarm with name: checkwhilerunning
>18:12:20 T:140681175623424  NOTICE: Thread TCPServer start, auto delete: false
>18:12:20 T:140681264449280   DEBUG: RunQuery took 1 ms for 0 items query: select * from movieview  ORDER BY dateAdded desc, idMovie desc LIMIT 10
>18:12:20 T:140681264449280   DEBUG: RunQuery took 1 ms for 0 items query: select * from episodeview  ORDER BY dateAdded desc, idEpisode desc LIMIT 10
>18:12:20 T:140681264449280   DEBUG: RunQuery took 0 ms for 0 items query: select * from musicvideoview  ORDER BY dateAdded desc, idMVideo desc LIMIT 10
>18:12:20 T:140681294173952    INFO: Python script stopped
>18:12:20 T:140681294173952   DEBUG: Thread LanguageInvoker 140681294173952 terminating
>18:12:20 T:140681264449280   DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
>18:12:20 T:140681282938624  NOTICE: Thread RSSReader start, auto delete: false
>18:12:20 T:140681282938624   DEBUG: CurlFile::Open(0x7ff2e9e55c60) http://feeds.xbmc.org/xbmc
>18:12:20 T:140681282938624    INFO: easy_aquire - Created session to http://feeds.xbmc.org
>18:12:21 T:140681768351744   DEBUG: ------ Window Init (Pointer.xml) ------
>18:12:22 T:140681282938624   ERROR: CCurlFile::FillBuffer - Failed: Timeout was reached(28)
>18:12:22 T:140681282938624   ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
>18:12:22 T:140681282938624   DEBUG: CurlFile::Open(0x7ff2e9e55c60) http://feeds.xbmc.org/xbmc
>18:12:24 T:140681282938624   ERROR: CCurlFile::FillBuffer - Failed: Timeout was reached(28)
>18:12:24 T:140681282938624   ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
>18:12:24 T:140681282938624   DEBUG: CurlFile::Open(0x7ff2e9e55c60) http://feeds.xbmc.org/xbmc
>18:12:24 T:140681768351744   DEBUG: ------ Window Deinit (Pointer.xml) ------
>18:12:25 T:140681768351744   DEBUG: ProcessMouse: trying mouse action leftclick
>18:12:25 T:140681768351744   DEBUG: Activating window ID: 10025
>18:12:25 T:140681768351744   DEBUG: ------ Window Init (Pointer.xml) ------
>18:12:25 T:140681768351744   DEBUG: ------ Window Deinit (Home.xml) ------
>18:12:25 T:140681768351744   DEBUG: ------ Window Init (MyVideoNav.xml) ------
>18:12:25 T:140681768351744    INFO: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY
>18:12:25 T:140681768351744   DEBUG: Load MyVideoNav.xml: 99.97ms
>18:12:25 T:140681768351744   DEBUG: Alloc resources: 131.80ms  (100.68 ms skin load)
>18:12:25 T:140681768351744   DEBUG: CGUIMediaWindow::GetDirectory (sources://video/)
>18:12:25 T:140681768351744   DEBUG:   ParentPath = [sources://video/]
>18:12:25 T:140680714254080  NOTICE: Thread BackgroundLoader start, auto delete: false
>18:12:25 T:140680714254080   DEBUG: Thread BackgroundLoader 140680714254080 terminating
>18:12:25 T:140681768351744   DEBUG: ------ Window Deinit (Pointer.xml) ------
>18:12:26 T:140681282938624   ERROR: CCurlFile::FillBuffer - Failed: Timeout was reached(28)
>18:12:26 T:140681282938624   ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
>18:12:26 T:140681282938624   DEBUG: CurlFile::Open(0x7ff2e9e55c60) http://feeds.xbmc.org/latest_xbmc_addons
>18:12:26 T:140681282938624   DEBUG: Got rss feed: http://feeds.xbmc.org/latest_xbmc_addons
>18:12:26 T:140681282938624   DEBUG: RSS feed encoding: UTF-8
>18:12:26 T:140681282938624   DEBUG: Parsed rss feed: http://feeds.xbmc.org/latest_xbmc_addons
>18:12:26 T:140681282938624   DEBUG: CurlFile::Open(0x7ff2e9e55c60) http://feeds.xbmc.org/updated_xbmc_addons
>18:12:26 T:140681282938624   DEBUG: Got rss feed: http://feeds.xbmc.org/updated_xbmc_addons
>18:12:26 T:140681282938624   DEBUG: RSS feed encoding: UTF-8
>18:12:26 T:140681282938624   DEBUG: Parsed rss feed: http://feeds.xbmc.org/updated_xbmc_addons
>18:12:26 T:140681282938624   DEBUG: Thread RSSReader 140681282938624 terminating
>18:12:26 T:140681768351744   DEBUG: ------ Window Init (Pointer.xml) ------
>18:12:27 T:140681768351744   DEBUG: ------ Window Deinit (Pointer.xml) ------
>18:12:27 T:140681768351744   DEBUG: ProcessMouse: trying mouse action leftclick
>18:12:27 T:140681768351744   DEBUG: CGUIMediaWindow::GetDirectory (/home/andy/Vids/)
>18:12:27 T:140681768351744   DEBUG:   ParentPath = [sources://video/]
>18:12:27 T:140681768351744   DEBUG: RunQuery took 1 ms for 10 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=1
>18:12:27 T:140681294173952  NOTICE: Thread BackgroundLoader start, auto delete: false
>18:12:27 T:140681294173952   DEBUG: Thread BackgroundLoader 140681294173952 terminating
>18:12:28 T:140681768351744   DEBUG: ------ Window Init (Pointer.xml) ------
>18:12:29 T:140681768351744    INFO: LIRC Initialize: using: /dev/lircd
>18:12:29 T:140681768351744   DEBUG: Failed to connect to LIRC. Retry in 20s.
>18:12:29 T:140681768351744   DEBUG: ------ Window Deinit (Pointer.xml) ------
>18:12:29 T:140681768351744   DEBUG: ProcessMouse: trying mouse action leftclick
>18:12:29 T:140681768351744   DEBUG: OnPlayMedia /home/andy/Vids/Letters.mpg
>18:12:29 T:140681768351744   DEBUG: GetMovieId (/home/andy/Vids/Letters.mpg), query = select idMovie from movie where idFile=8
>18:12:29 T:140681768351744   DEBUG: GetEpisodeId (/home/andy/Vids/Letters.mpg), query = select idEpisode from episode where idFile=8
>18:12:29 T:140681768351744   DEBUG: GetMusicVideoId (/home/andy/Vids/Letters.mpg), query = select idMVideo from musicvideo where idFile=8
>18:12:29 T:140681768351744   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
>18:12:29 T:140681768351744   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
>18:12:29 T:140681768351744   DEBUG: Loading settings for /home/andy/Vids/Letters.mpg
>18:12:29 T:140681768351744   DEBUG: CPlayerCoreFactory::GetPlayers(/home/andy/Vids/Letters.mpg)
>18:12:29 T:140681768351744   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
>18:12:29 T:140681768351744   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
>18:12:29 T:140681768351744   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
>18:12:29 T:140681768351744   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
>18:12:29 T:140681768351744   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
>18:12:29 T:140681768351744   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
>18:12:29 T:140681768351744   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
>18:12:29 T:140681768351744   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
>18:12:29 T:140681768351744   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
>18:12:29 T:140681768351744   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
>18:12:29 T:140681768351744   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
>18:12:29 T:140681768351744   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
>18:12:29 T:140681768351744   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
>18:12:29 T:140681768351744   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
>18:12:29 T:140681768351744   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
>18:12:29 T:140681768351744   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
>18:12:29 T:140681768351744   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
>18:12:29 T:140681768351744   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
>18:12:29 T:140681768351744   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
>18:12:29 T:140681768351744  NOTICE: DVDPlayer: Opening: /home/andy/Vids/Letters.mpg
>18:12:29 T:140681768351744 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
>18:12:29 T:140681768351744   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
>18:12:29 T:140681768351744   DEBUG: LinuxRendererGL: Cleaning up GL resources
>18:12:29 T:140681768351744   DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
>18:12:29 T:140681294173952  NOTICE: Thread DVDPlayer start, auto delete: false
>18:12:29 T:140681294173952  NOTICE: Creating InputStream
>18:12:29 T:140681294173952   DEBUG: ScanForExternalSubtitles: Searching for subtitles...
>18:12:29 T:140681294173952   DEBUG: ScanForExternalSubtitles: END (total time: 1 ms)
>18:12:29 T:140681294173952  NOTICE: Creating Demuxer
>18:12:29 T:140681294173952   DEBUG: Open - probing detected format [mpeg]
>18:12:29 T:140681294173952   DEBUG: Open - avformat_find_stream_info starting
>18:12:29 T:140681294173952   DEBUG: Open - av_find_stream_info finished
>18:12:29 T:140681294173952    INFO: ffmpeg[7FF2EA90D700]: Input #0, mpeg, from '/home/andy/Vids/Letters.mpg':
>18:12:29 T:140681294173952    INFO: ffmpeg[7FF2EA90D700]:   Duration: 00:00:29.96, start: 0.500000, bitrate: 8040 kb/s
>18:12:29 T:140681294173952    INFO: ffmpeg[7FF2EA90D700]:     Stream #0:0[0x1e0]: Video: mpeg2video (Main), yuv420p(tv), 720x576 [SAR 64:45 DAR 16:9], 8000 kb/s, 25 fps, 25 tbr, 90k tbn, 50 tbc
>18:12:29 T:140681294173952   DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
>18:12:29 T:140681294173952  NOTICE: Opening stream: 0 source: 256
>18:12:29 T:140681294173952  NOTICE: Creating video codec with codec id: 2
>18:12:29 T:140681294173952   DEBUG: CDVDFactoryCodec: compiled in hardware support: AMCodec:no MediaCodec:no OpenMax:no libstagefright:no VDPAU:yes VAAPI:yes iMXVPU:no
>18:12:29 T:140681294173952   DEBUG: FactoryCodec - Video:  - Opening
>18:12:29 T:140681294173952  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video
>18:12:29 T:140681294173952   DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened
>18:12:29 T:140681294173952  NOTICE: Creating video thread
>18:12:29 T:140681294173952   ERROR: Get - failed to get stream
>18:12:29 T:140681294173952   DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /home/andy/Vids/Letters.mpg
>18:12:29 T:140681294173952   DEBUG: OnPlayBackStarted: play state was 1, starting 1
>18:12:29 T:140681294173952   DEBUG: CDVDPlayer::SetCaching - caching state 3
>18:12:29 T:140681294173952 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED
>18:12:29 T:140680714254080  NOTICE: Thread DVDPlayerVideo start, auto delete: false
>18:12:29 T:140680714254080  NOTICE: running thread: video_thread
>18:12:29 T:140681768351744   DEBUG: PlayFile: OpenFile succeed, play state 2
>18:12:29 T:140681768351744   DEBUG: OnPlayBackStarted: play state was 2, starting 0
>18:12:29 T:140680714254080   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(0.000000, 1)
>18:12:29 T:140680714254080    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
>18:12:29 T:140680714254080   DEBUG: VAAPI - open decoder
>18:12:29 T:140681768351744   DEBUG: CGUIInfoManager::SetCurrentMovie(/home/andy/Vids/Letters.mpg)
>18:12:29 T:140681768351744   DEBUG: GetMovieId (/home/andy/Vids/Letters.mpg), query = select idMovie from movie where idFile=8
>18:12:29 T:140681768351744   DEBUG: GetEpisodeId (/home/andy/Vids/Letters.mpg), query = select idEpisode from episode where idFile=8
>18:12:29 T:140681768351744   DEBUG: GetMusicVideoId (/home/andy/Vids/Letters.mpg), query = select idMVideo from musicvideo where idFile=8
>18:12:29 T:140681768351744   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
>18:12:29 T:140681768351744   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
>18:12:29 T:140680714254080   DEBUG: VAAPI - initialize version 0.36
>18:12:29 T:140680714254080   DEBUG: VAAPI - attrib 0 (get/set) min -100 max 100 value 0x0
>18:12:29 T:140680714254080   DEBUG: VAAPI - attrib 1 (get/set) min 0 max 100 value 0x32
>18:12:29 T:140680714254080   DEBUG: VAAPI - attrib 2 (get/set) min -180 max 180 value 0x0
>18:12:29 T:140680714254080   DEBUG: VAAPI - attrib 3 (get/set) min 0 max 100 value 0x32
>18:12:29 T:140680714254080   DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0
>18:12:29 T:140680714254080   DEBUG: VAAPI - profile 0
>18:12:29 T:140680714254080   DEBUG: VAAPI - profile 1
>18:12:29 T:140680714254080   DEBUG: VAAPI - profile 13
>18:12:29 T:140680714254080   DEBUG: VAAPI - profile 6
>18:12:29 T:140680714254080   DEBUG: VAAPI - profile 7
>18:12:29 T:140680714254080   DEBUG: VAAPI - profile 16
>18:12:29 T:140680714254080   DEBUG: VAAPI - profile 8
>18:12:29 T:140680714254080   DEBUG: VAAPI - profile 9
>18:12:29 T:140680714254080   DEBUG: VAAPI - profile 10
>18:12:29 T:140680714254080   DEBUG: VAAPI - profile -1
>18:12:29 T:140680714254080   DEBUG: VAAPI - profile 12
>18:12:29 T:140680705337088  NOTICE: Thread Vaapi-Output start, auto delete: false
>18:12:29 T:140680705337088  NOTICE: COutput::OnStartup: Output Thread created
>18:12:29 T:140680705337088  NOTICE: VAAPI::COutput::CreateGlxContext - created context
>18:12:29 T:140680705337088   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libsse4-x86_64-linux.so)
>18:12:29 T:140680705337088   DEBUG: Loading: /home/andy/Src/xbmc/system/libsse4-x86_64-linux.so
>18:12:29 T:140680705337088  NOTICE: VAAPI::COutput::InitBufferPool - Surfaces created
>18:12:29 T:140680714254080  NOTICE:  fps: 25.000000, pwidth: 720, pheight: 576, dwidth: 720, dheight: 405
>18:12:29 T:140680714254080   DEBUG: OutputPicture - change configuration. 720x576. framerate: 25.00. format: VAAPI
>18:12:29 T:140680714254080  NOTICE: Display resolution DESKTOP : 1920x1080@ 50.00 - Full Screen (16)
>18:12:29 T:140680714254080   DEBUG: CXBMCRenderManager::Configure - 4
>18:12:29 T:140680714254080   DEBUG: CDVDClock::Discontinuity - CDVDPlayerVideo::OutputPicture - was:42065.739000, should be:-40000.000000, error:-82065.739000
>18:12:29 T:140681768351744   DEBUG: Activating window ID: 12005
>18:12:29 T:140681768351744   DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
>18:12:29 T:140681768351744   DEBUG: ------ Window Init (VideoFullScreen.xml) ------
>18:12:29 T:140681768351744    INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
>18:12:29 T:140681768351744   DEBUG: Load VideoFullScreen.xml: 16.33ms
>18:12:29 T:140681768351744   DEBUG: Alloc resources: 25.62ms  (16.50 ms skin load)
>18:12:29 T:140681768351744  NOTICE: Using GL_TEXTURE_2D
>18:12:29 T:140681768351744  NOTICE: GL: Using VAAPI render method
>18:12:29 T:140681768351744  NOTICE: GL: NPOT texture support detected
>18:12:29 T:140681768351744  NOTICE: GL: Using GL_ARB_pixel_buffer_object
>18:12:29 T:140681294173952   DEBUG: CDVDPlayer::HandleMessages - player started 2
>18:12:29 T:140681294173952   DEBUG: CDVDPlayer::SetCaching - caching state 0
>18:12:29 T:140680714254080   DEBUG: CDVDClock::Discontinuity - CDVDPlayerVideo::OutputPicture - was:-35780.658000, should be:178889.586000, error:214670.244000
>18:12:29 T:140680714254080   DEBUG: CDVDClock::Discontinuity - CDVDPlayerVideo::OutputPicture - was:178943.682000, should be:40054.096000, error:-138889.586000
>18:12:29 T:140681768351744   DEBUG: GL: Vertex Shader compilation log:
>18:12:29 T:140681768351744   DEBUG: GL: Vertex Shader compiled successfully
>18:12:29 T:140681768351744   DEBUG: GL: Pixel Shader compilation log:
>18:12:29 T:140681768351744   DEBUG: GL: Fragment Shader compiled successfully
>18:12:30 T:140681768351744    INFO: Python, unloading python shared library because no scripts are running anymore
>18:12:30 T:140681768351744   DEBUG: UnloadExtensionLibs, clearing python extension libraries
>18:12:35 T:140680714254080   DEBUG: CPullupCorrection: detected pattern of length 48: 20000.00 20000.00 20000.00 20000.00 20000.00 20000.00 40000.00 0.00 -20000.00 40000.00 40000.00 20000.00 40000.00 0.00 -20000.00 40000.00 40000.00 20000.00 40000.00 0.00 -20000.00 40000.00 40000.00 20000.00 40000.00 0.00 -20000.00 40000.00 40000.00 20000.00 40000.00 0.00 -20000.00 40000.00 40000.00 20000.00 40000.00 0.00 -20000.00 40000.00 40000.00 20000.00 40000.00 0.00 -20000.00 40000.00 40000.00 20000.00, frameduration: 20000.000000
>18:12:36 T:140680714254080   DEBUG: CPullupCorrection: pattern lost on diff 20000.000000, number of losses 1
>18:12:40 T:140681768351744   DEBUG: ------ Window Init (Pointer.xml) ------
>18:12:41 T:140681768351744   DEBUG: ------ Window Init (VideoOSD.xml) ------
>18:12:41 T:140681768351744    INFO: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY
>18:12:41 T:140681768351744   DEBUG: Load VideoOSD.xml: 20.67ms
>18:12:41 T:140681768351744   DEBUG: Alloc resources: 66.94ms  (20.90 ms skin load)
>18:12:43 T:140681768351744   DEBUG: ------ Window Deinit (Pointer.xml) ------
>18:12:43 T:140681768351744   DEBUG: ProcessMouse: trying mouse action leftclick
>18:12:43 T:140681768351744  NOTICE: CDVDPlayer::CloseFile()
>18:12:43 T:140681768351744  NOTICE: DVDPlayer: waiting for threads to exit
>18:12:43 T:140681294173952  NOTICE: CDVDPlayer::OnExit()
>18:12:43 T:140681294173952  NOTICE: Closing stream player 2
>18:12:43 T:140681294173952  NOTICE: waiting for video thread to exit
>18:12:43 T:140680714254080  NOTICE: thread end: video_thread
>18:12:43 T:140680714254080   DEBUG: Thread DVDPlayerVideo 140680714254080 terminating
>18:12:43 T:140681294173952  NOTICE: deleting video codec
>18:12:43 T:140681294173952  NOTICE: VAAPI::Release pre-cleanup
>18:12:43 T:140681294173952   DEBUG: OnPlayBackStopped: play state was 2, starting 0
>18:12:43 T:140681294173952   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
>18:12:43 T:140681294173952   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
>18:12:43 T:140681294173952   DEBUG: Thread DVDPlayer 140681294173952 terminating
>18:12:43 T:140681768351744  NOTICE: DVDPlayer: finished waiting
>18:12:43 T:140681768351744   DEBUG: LinuxRendererGL: Cleaning up GL resources
>18:12:43 T:140681768351744  NOTICE: VAAPI::Close
>18:12:43 T:140680705337088  NOTICE: COutput::OnExit: Output Thread terminated
>18:12:43 T:140680705337088   DEBUG: Thread Vaapi-Output 140680705337088 terminating
>18:12:43 T:140681768351744   DEBUG: VAAPI::FiniVAAPIOutput destroying 0 video surfaces
>18:12:43 T:140681768351744  NOTICE: VAAPI::Close - closing decoder context
>18:12:43 T:140681768351744   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
>18:12:43 T:140681768351744   DEBUG: ------ Window Deinit (VideoOSD.xml) ------
>18:12:43 T:140681768351744   DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
>18:12:43 T:140681768351744   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
>18:12:43 T:140681768351744   DEBUG: ------ Window Init (MyVideoNav.xml) ------
>18:12:43 T:140681768351744   DEBUG: Window MyVideoNav.xml was already loaded
>18:12:43 T:140681768351744   DEBUG: Alloc resources: 29.75m
>18:12:43 T:140681768351744   DEBUG: CGUIMediaWindow::GetDirectory (/home/andy/Vids/)
>18:12:43 T:140681768351744   DEBUG:   ParentPath = [/home/andy/Vids/]
>18:12:43 T:140681768351744   DEBUG: RunQuery took 0 ms for 10 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=1
>18:12:43 T:140680705337088  NOTICE: Thread BackgroundLoader start, auto delete: false
>18:12:44 T:140680705337088   DEBUG: Thread BackgroundLoader 140680705337088 terminating
>18:12:44 T:140681768351744  NOTICE: CDVDPlayer::CloseFile()
>18:12:44 T:140681768351744  NOTICE: DVDPlayer: waiting for threads to exit
>18:12:44 T:140681768351744  NOTICE: DVDPlayer: finished waiting
>18:12:44 T:140681768351744   DEBUG: LinuxRendererGL: Cleaning up GL resources
>18:12:44 T:140681768351744  NOTICE: CDVDPlayer::CloseFile()
>18:12:44 T:140681768351744  NOTICE: DVDPlayer: waiting for threads to exit
>18:12:44 T:140681768351744  NOTICE: DVDPlayer: finished waiting
>18:12:44 T:140681768351744   DEBUG: LinuxRendererGL: Cleaning up GL resources
>18:12:44 T:140681264449280   DEBUG: DoWork - Saving file state for video item /home/andy/Vids/Letters.mpg
>18:12:44 T:140681768351744   DEBUG: ------ Window Init (Pointer.xml) ------
>18:12:46 T:140681768351744   DEBUG: ------ Window Deinit (Pointer.xml) ------
>18:12:46 T:140681768351744   DEBUG: ProcessMouse: trying mouse action leftclick
>18:12:46 T:140681768351744   DEBUG: CGUIMediaWindow::GetDirectory (sources://video/)
>18:12:46 T:140681768351744   DEBUG:   ParentPath = []
>18:12:46 T:140680705337088  NOTICE: Thread BackgroundLoader start, auto delete: false
>18:12:46 T:140680705337088   DEBUG: Thread BackgroundLoader 140680705337088 terminating
>18:12:47 T:140681768351744   DEBUG: ProcessMouse: trying mouse action leftclick
>18:12:47 T:140681768351744   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
>18:12:47 T:140681768351744   DEBUG: ------ Window Init (Pointer.xml) ------
>18:12:48 T:140681768351744   DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
>18:12:48 T:140681768351744   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
>18:12:48 T:140681768351744   DEBUG: ------ Window Init (Home.xml) ------
>18:12:48 T:140681768351744   DEBUG: Window Home.xml was already loaded
>18:12:48 T:140681768351744   DEBUG: Alloc resources: 20.69m
>18:12:48 T:140681768351744   DEBUG: ------ Window Deinit (Pointer.xml) ------
>18:12:48 T:140681768351744   DEBUG: ------ Window Init (Pointer.xml) ------
>18:12:49 T:140681768351744    INFO: LIRC Initialize: using: /dev/lircd
>18:12:49 T:140681768351744   DEBUG: Failed to connect to LIRC. Retry in 40s.
>18:12:50 T:140681768351744   DEBUG: ------ Window Deinit (Pointer.xml) ------
>18:12:50 T:140681768351744   DEBUG: ProcessMouse: trying mouse action leftclick
>18:12:50 T:140681768351744   DEBUG: Activating window ID: 10111
>18:12:50 T:140681768351744   DEBUG: ------ Window Init (DialogButtonMenu.xml) ------
>18:12:50 T:140681768351744    INFO: Loading skin file: DialogButtonMenu.xml, load type: KEEP_IN_MEMORY
>18:12:50 T:140681768351744   DEBUG: Load DialogButtonMenu.xml: 7.43ms
>18:12:50 T:140681768351744   DEBUG: Alloc resources: 13.36ms  (7.49 ms skin load)
>18:12:51 T:140681768351744   DEBUG: ------ Window Init (Pointer.xml) ------
>18:12:52 T:140681768351744   DEBUG: ------ Window Deinit (Pointer.xml) ------
>18:12:52 T:140681768351744   DEBUG: ProcessMouse: trying mouse action leftclick
>18:12:52 T:140681768351744   DEBUG: CAnnouncementManager - Announcement: OnQuit from xbmc
>18:12:52 T:140681768351744   DEBUG: GOT ANNOUNCEMENT, type: 8, from xbmc, message OnQuit
>18:12:52 T:140681167230720   DEBUG: Thread AlarmClock 140681167230720 terminating
>18:12:52 T:140681768351744  NOTICE: Storing total System Uptime
>18:12:52 T:140681768351744  NOTICE: Saving settings
>18:12:52 T:140681768351744  NOTICE: stop all
>18:12:52 T:140681264449280   DEBUG: Thread JobWorker 140681264449280 terminating (autodelete)
>18:12:52 T:140681768351744  NOTICE: stop player
>18:12:52 T:140681768351744    INFO: stopping PVRManager
>18:12:52 T:140681768351744   DEBUG: NetworkMessage - Signaling network services to stop
>18:12:52 T:140681768351744  NOTICE: ES: Stopping event server
>18:12:52 T:140681768351744   DEBUG: NetworkMessage - Waiting for network services to stop
>18:12:52 T:140681768351744  NOTICE: stopping upnp
>18:12:53 T:140681184016128  NOTICE: ES: UDP Event server stopped
>18:12:53 T:140681184016128   DEBUG: Thread EventServer 140681184016128 terminating
>18:12:53 T:140681175623424   DEBUG: Thread TCPServer 140681175623424 terminating
>18:12:53 T:140681547486976   DEBUG: Thread PeripBusUSBUdev 140681547486976 terminating
>18:12:53 T:140681768351744  NOTICE: stop sap announcement listener
>18:12:53 T:140681768351744  NOTICE: clean cached files!
>18:12:53 T:140681768351744  NOTICE: unload skin
>18:12:53 T:140681768351744    INFO: Unloading old skin ...
>18:12:53 T:140681768351744   DEBUG: ------ Window Deinit () ------
>18:12:53 T:140681768351744   DEBUG: ------ Window Deinit (Home.xml) ------
>18:12:53 T:140681768351744   DEBUG: Infobool 'player.showcodec' still used by 2 instances
>18:12:53 T:140681768351744   DEBUG: ADDON: Stopping service addons.
>18:12:53 T:140681768351744    INFO: XRANDR: /home/andy/Src/xbmc/kodi-xrandr --screen 0 --output HDMI1 --mode 0x49
>18:12:53 T:140681566447360   DEBUG: Thread ActiveAE 140681566447360 terminating
>18:12:53 T:140681558054656   DEBUG: Thread AESink 140681558054656 terminating
>18:12:53 T:140681768351744  NOTICE: stopped
>18:12:53 T:140681768351744  NOTICE: destroy
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in visualization.fishbmc has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.euphoria has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in visualization.vortex has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.wma has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in repository.pvr-osx32.xbmc.org has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in visualization.projectm has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in service.xbmc.versioncheck has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in repository.pvr-ios.xbmc.org has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in skin.confluence has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in visualization.glspectrum has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.amazon.de has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in visualization.milkdrop has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in visualization.dxspectrum has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.musicvideos.theaudiodb.com has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.solarwinds has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in repository.pvr-android.xbmc.org has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.aac has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in repository.pvr-win32.xbmc.org has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in repository.pvr-osx64.xbmc.org has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.last.fm has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.plasma has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in visualization.waveform has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been uninstalled.'
>18:12:53 T:140681768351744   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.hdtrailers.net has been uninstalled.'
>18:12:53 T:140681768351744    INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x435d900 with reference count 1 when destroying the associated plug-in context. Not releasing the object.'
>18:12:53 T:140681768351744    INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x4436250 with reference count 1 when destroying the associated plug-in context. Not releasing the object.'
>18:12:53 T:140681768351744    INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x44513e0 with reference count 1 when destroying the associated plug-in context. Not releasing the object.'
>18:12:53 T:140681768351744  NOTICE: closing down remote control service
>18:12:53 T:140681768351744  NOTICE: unload sections
>18:12:53 T:140681768351744   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
>18:12:53 T:140681768351744  NOTICE: special://profile/ is mapped to: special://masterprofile/
>18:12:53 T:140681768351744   DEBUG: object 0 --> 0 instances
>18:12:53 T:140681768351744   DEBUG: object 1 --> 0 instances
>18:12:53 T:140681768351744   DEBUG: object 2 --> 0 instances
>18:12:53 T:140681768351744   DEBUG: object 3 --> 0 instances
>18:12:53 T:140681768351744   DEBUG: object 4 --> 0 instances
>18:12:53 T:140681768351744   DEBUG: object 5 --> 0 instances
>18:12:53 T:140681768351744   DEBUG: object 6 --> 0 instances
>18:12:53 T:140681768351744   DEBUG: object 7 --> 0 instances
>18:12:53 T:140681768351744   DEBUG: object 8 --> 0 instances
>18:12:53 T:140681768351744   DEBUG: object 9 --> 0 instances
>18:12:54 T:140681768351744   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
>18:12:54 T:140681768351744  NOTICE: destroy
>18:12:54 T:140681768351744 WARNING: Attempted to remove window 10013 from the window manager when it didn't exist
>18:12:54 T:140681768351744 WARNING: Attempted to remove window 10014 from the window manager when it didn't exist
>18:12:54 T:140681768351744 WARNING: Attempted to remove window 10015 from the window manager when it didn't exist
>18:12:54 T:140681768351744 WARNING: Attempted to remove window 10016 from the window manager when it didn't exist
>18:12:54 T:140681768351744 WARNING: Attempted to remove window 10017 from the window manager when it didn't exist
>18:12:54 T:140681768351744 WARNING: Attempted to remove window 10018 from the window manager when it didn't exist
>18:12:54 T:140681768351744 WARNING: Attempted to remove window 10019 from the window manager when it didn't exist
>18:12:54 T:140681768351744 WARNING: Attempted to remove window 10021 from the window manager when it didn't exist
>18:12:54 T:140681768351744 WARNING: Attempted to remove window 10107 from the window manager when it didn't exist
>18:12:54 T:140681768351744 WARNING: Attempted to remove window 10115 from the window manager when it didn't exist
>18:12:54 T:140681768351744 WARNING: Attempted to remove window 10104 from the window manager when it didn't exist
>18:12:54 T:140681768351744  NOTICE: closing down remote control service
>18:12:54 T:140681768351744  NOTICE: unload sections
>18:12:54 T:140681768351744   DEBUG: object 0 --> 0 instances
>18:12:54 T:140681768351744   DEBUG: object 1 --> 0 instances
>18:12:54 T:140681768351744   DEBUG: object 2 --> 0 instances
>18:12:54 T:140681768351744   DEBUG: object 3 --> 0 instances
>18:12:54 T:140681768351744   DEBUG: object 4 --> 0 instances
>18:12:54 T:140681768351744   DEBUG: object 5 --> 0 instances
>18:12:54 T:140681768351744   DEBUG: object 6 --> 0 instances
>18:12:54 T:140681768351744   DEBUG: object 7 --> 0 instances
>18:12:54 T:140681768351744   DEBUG: object 8 --> 0 instances
>18:12:54 T:140681768351744   DEBUG: object 9 --> 0 instances
>18:12:54 T:140681768351744  NOTICE: application stopped...
>18:12:54 T:140681768351744   DEBUG: PVRManager - destroyed
>18:12:54 T:140681768351744   DEBUG: SECTION:UnloadDll(libcurl.so.4)
>18:12:54 T:140681768351744   DEBUG: Unloading:
>18:12:54 T:140681768351744   DEBUG: LinuxRendererGL: Cleaning up GL resources
>18:12:54 T:140681768351744   DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/libcpluff-x86_64-linux.so)
>18:12:54 T:140681768351744   DEBUG: Unloading: /libcpluff-x86_64-linux.so
>18:12:54 T:140681768351744   DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/ImageLib-x86_64-linux.so)
>18:12:54 T:140681768351744   DEBUG: Unloading: /ImageLib-x86_64-linux.so
>18:12:54 T:140681768351744   DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/libsse4-x86_64-linux.so)
>18:12:54 T:140681768351744   DEBUG: Unloading: /libsse4-x86_64-linux.so

Actions: View

Attachments on bug 79765: 100593 | 100594 | 100595 | 108529 | 108531 | 108533 | 108600 | 108601 | 114242 | 114521 | 114522 | 114651

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.