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::: created player DVDPlayer for core 1 18:12:18 T:140681768351744 DEBUG: CPlayerCoreConfig::: created player oldmplayercore for core 1 18:12:18 T:140681768351744 DEBUG: CPlayerCoreConfig::: 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