22:17:33.079 T:140523296773376 NOTICE: special://profile/ is mapped to: special://masterprofile/
22:17:33.079 T:140523296773376 NOTICE: -----------------------------------------------------------------------
22:17:33.079 T:140523296773376 NOTICE: Starting Kodi (17.0 Git:a10c504). Platform: Linux x86 64-bit
22:17:33.079 T:140523296773376 NOTICE: Using Release Kodi x64 build
22:17:33.079 T:140523296773376 NOTICE: Kodi compiled Feb 17 2017 by GCC 6.2.0 for Linux x86 64-bit version 4.9.8 (264456)
22:17:33.079 T:140523296773376 NOTICE: Running on LibreELEC (official) - Version: 8.0.0, kernel: Linux x86 64-bit version 4.9.8
22:17:33.079 T:140523296773376 NOTICE: FFmpeg version/source: ffmpeg-3.1-kodi
22:17:33.079 T:140523296773376 NOTICE: Host CPU: Intel(R) Core(TM) i3-6100U CPU @ 2.30GHz, 4 cores available
22:17:33.079 T:140523296773376 NOTICE: special://xbmc/ is mapped to: /usr/share/kodi/
22:17:33.079 T:140523296773376 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/kodi
22:17:33.079 T:140523296773376 NOTICE: special://xbmcbinaddons/ is mapped to: /usr/lib/kodi/addons
22:17:33.079 T:140523296773376 NOTICE: special://masterprofile/ is mapped to: /storage/.kodi/userdata
22:17:33.079 T:140523296773376 NOTICE: special://envhome/ is mapped to: /storage
22:17:33.079 T:140523296773376 NOTICE: special://home/ is mapped to: /storage/.kodi
22:17:33.079 T:140523296773376 NOTICE: special://temp/ is mapped to: /storage/.kodi/temp
22:17:33.079 T:140523296773376 NOTICE: special://logpath/ is mapped to: /storage/.kodi/temp
22:17:33.079 T:140523296773376 NOTICE: The executable running is: /usr/lib/kodi/kodi.bin
22:17:33.079 T:140523296773376 NOTICE: Local hostname: LibreELEC
22:17:33.079 T:140523296773376 NOTICE: Log File is located: /storage/.kodi/temp//kodi.log
22:17:33.080 T:140523296773376 NOTICE: -----------------------------------------------------------------------
22:17:33.115 T:140523296773376 ERROR: DBus: Error org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files
22:17:33.118 T:140523296773376 NOTICE: load settings...
22:17:33.812 T:140523296773376 NOTICE: Found 2 Lists of Devices
22:17:33.812 T:140523296773376 NOTICE: Enumerated ALSA devices:
22:17:33.812 T:140523296773376 NOTICE: Device 1
22:17:33.812 T:140523296773376 NOTICE: m_deviceName : @
22:17:33.812 T:140523296773376 NOTICE: m_displayName : Default (HDA Intel PCH ALC283 Analog)
22:17:33.812 T:140523296773376 NOTICE: m_displayNameExtra:
22:17:33.812 T:140523296773376 NOTICE: m_deviceType : AE_DEVTYPE_PCM
22:17:33.812 T:140523296773376 NOTICE: m_channels : FL,FR
22:17:33.812 T:140523296773376 NOTICE: m_sampleRates : 48000
22:17:33.812 T:140523296773376 NOTICE: m_dataFormats : AE_FMT_S32NE
22:17:33.812 T:140523296773376 NOTICE: m_streamTypes : No passthrough capabilities
22:17:33.812 T:140523296773376 NOTICE: Device 2
22:17:33.812 T:140523296773376 NOTICE: m_deviceName : @:CARD=PCH,DEV=0
22:17:33.812 T:140523296773376 NOTICE: m_displayName : HDA Intel PCH
22:17:33.812 T:140523296773376 NOTICE: m_displayNameExtra: ALC283 Analog
22:17:33.812 T:140523296773376 NOTICE: m_deviceType : AE_DEVTYPE_PCM
22:17:33.812 T:140523296773376 NOTICE: m_channels : FL,FR
22:17:33.812 T:140523296773376 NOTICE: m_sampleRates : 48000
22:17:33.812 T:140523296773376 NOTICE: m_dataFormats : AE_FMT_S32NE
22:17:33.812 T:140523296773376 NOTICE: m_streamTypes : No passthrough capabilities
22:17:33.812 T:140523296773376 NOTICE: Device 3
22:17:33.812 T:140523296773376 NOTICE: m_deviceName : hdmi:CARD=PCH,DEV=0
22:17:33.812 T:140523296773376 NOTICE: m_displayName : HDA Intel PCH
22:17:33.812 T:140523296773376 NOTICE: m_displayNameExtra: SEC EPSON PJ on HDMI #0
22:17:33.812 T:140523296773376 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
22:17:33.812 T:140523296773376 NOTICE: m_channels : FL,FR
22:17:33.812 T:140523296773376 NOTICE: m_sampleRates : 32000,44100,48000
22:17:33.812 T:140523296773376 NOTICE: m_dataFormats : AE_FMT_RAW,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW
22:17:33.812 T:140523296773376 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512,STREAM_TYPE_EAC3,STREAM_TYPE_TRUEHD
22:17:33.812 T:140523296773376 NOTICE: Device 4
22:17:33.812 T:140523296773376 NOTICE: m_deviceName : hdmi:CARD=PCH,DEV=1
22:17:33.812 T:140523296773376 NOTICE: m_displayName : HDA Intel PCH
22:17:33.812 T:140523296773376 NOTICE: m_displayNameExtra: HDMI #1
22:17:33.812 T:140523296773376 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
22:17:33.812 T:140523296773376 NOTICE: m_channels : FL,FR
22:17:33.812 T:140523296773376 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000
22:17:33.812 T:140523296773376 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW
22:17:33.812 T:140523296773376 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512,STREAM_TYPE_EAC3,STREAM_TYPE_TRUEHD
22:17:33.812 T:140523296773376 NOTICE: Device 5
22:17:33.812 T:140523296773376 NOTICE: m_deviceName : hdmi:CARD=PCH,DEV=2
22:17:33.812 T:140523296773376 NOTICE: m_displayName : HDA Intel PCH
22:17:33.812 T:140523296773376 NOTICE: m_displayNameExtra: HDMI #2
22:17:33.812 T:140523296773376 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
22:17:33.812 T:140523296773376 NOTICE: m_channels : FL,FR
22:17:33.813 T:140523296773376 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000
22:17:33.813 T:140523296773376 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW
22:17:33.813 T:140523296773376 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512,STREAM_TYPE_EAC3,STREAM_TYPE_TRUEHD
22:17:33.813 T:140523296773376 NOTICE: Enumerated PULSE devices:
22:17:33.813 T:140523296773376 NOTICE: Device 1
22:17:33.813 T:140523296773376 NOTICE: m_deviceName : Default
22:17:33.813 T:140523296773376 NOTICE: m_displayName : Default
22:17:33.813 T:140523296773376 NOTICE: m_displayNameExtra: Bluetooth Audio (PULSEAUDIO)
22:17:33.813 T:140523296773376 NOTICE: m_deviceType : AE_DEVTYPE_PCM
22:17:33.813 T:140523296773376 NOTICE: m_channels : FL,FR
22:17:33.813 T:140523296773376 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
22:17:33.813 T:140523296773376 NOTICE: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT
22:17:33.813 T:140523296773376 NOTICE: m_streamTypes : No passthrough capabilities
22:17:33.817 T:140523296773376 NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml
22:17:33.817 T:140523296773376 NOTICE: Contents of special://xbmc/system/advancedsettings.xml are...
false
1
cputemp
gputemp
30
22:17:33.817 T:140523296773376 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
22:17:33.817 T:140523296773376 NOTICE: Default Video Player: VideoPlayer
22:17:33.817 T:140523296773376 NOTICE: Default Audio Player: paplayer
22:17:33.817 T:140523296773376 NOTICE: Enabled debug logging due to GUI setting (2)
22:17:33.817 T:140523296773376 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM"
22:17:33.817 T:140523296773376 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
22:17:33.818 T:140523296773376 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
22:17:33.818 T:140523296773376 DEBUG: CPlayerCoreConfig::: created player VideoPlayer
22:17:33.818 T:140523296773376 DEBUG: CPlayerCoreConfig::: created player PAPlayer
22:17:33.818 T:140523296773376 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
22:17:33.818 T:140523296773376 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mms/udp
22:17:33.818 T:140523296773376 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
22:17:33.818 T:140523296773376 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
22:17:33.818 T:140523296773376 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
22:17:33.818 T:140523296773376 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
22:17:33.818 T:140523296773376 DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
22:17:33.818 T:140523296773376 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
22:17:33.818 T:140523296773376 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
22:17:33.818 T:140523296773376 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
22:17:33.818 T:140523296773376 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
22:17:33.818 T:140523296773376 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
22:17:33.818 T:140523296773376 DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
22:17:33.818 T:140523296773376 NOTICE: Loaded playercorefactory configuration
22:17:33.818 T:140523296773376 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
22:17:33.818 T:140523296773376 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
22:17:33.821 T:140523296773376 INFO: creating subdirectories
22:17:33.821 T:140523296773376 INFO: userdata folder: special://masterprofile/
22:17:33.821 T:140523296773376 INFO: recording folder:
22:17:33.821 T:140523296773376 INFO: screenshots folder: /storage/screenshots/
22:17:33.831 T:140523296773376 NOTICE: Running database version Addons27
22:17:33.831 T:140523296773376 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-x86_64-linux.so)
22:17:33.831 T:140523296773376 DEBUG: Loading: /usr/lib/kodi/system/libcpluff-x86_64-linux.so
22:17:33.835 T:140523296773376 INFO: ADDON: cpluff: 'Could not read plug-in directory /storage/.kodi/addons: No such file or directory'
22:17:33.854 T:140523296773376 INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/lib/kodi/addons: No such file or directory'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in resource.images.weathericons.default has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in xbmc.audioencoder has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in kodi.audiodecoder has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in kodi.peripheral has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in kodi.guilib has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in peripheral.joystick has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in kodi.adsp has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.wma has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in game.controller.default has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.ofdb.de has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in metadata.universal has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in service.libreelec.settings has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in kodi.game has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in kodi.resource has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in os.openelec.tv has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in repository.kodi.game has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in os.libreelec.tv has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in kodi.inputstream has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in repository.libreelec.tv has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.aac has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.movieposterdb.com has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in resource.language.en_gb has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.port.hu has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in xbmc.webinterface has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.omdbapi.com has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.kodi has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Plug-in skin.estuary has been installed.'
22:17:33.854 T:140523296773376 DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
22:17:33.861 T:140523075532544 DEBUG: Thread JobWorker start, auto delete: true
22:17:33.869 T:140523296773376 NOTICE: ADDONS: Using repository repository.kodi.game
22:17:33.869 T:140523296773376 NOTICE: ADDONS: Using repository repository.libreelec.tv
22:17:33.869 T:140523296773376 NOTICE: ADDONS: Using repository repository.xbmc.org
22:17:33.871 T:140523206473472 DEBUG: Thread ActiveAE start, auto delete: false
22:17:33.871 T:140523198080768 DEBUG: Thread AESink start, auto delete: false
22:17:33.872 T:140523198080768 INFO: CActiveAESink::OpenSink - initialize sink
22:17:33.872 T:140523198080768 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@
22:17:33.872 T:140523198080768 INFO: CAESinkALSA::Initialize - Attempting to open device "@"
22:17:33.876 T:140523198080768 INFO: CAESinkALSA::Initialize - Opened device "sysdefault"
22:17:33.876 T:140523198080768 DEBUG: CAESinkALSA::SelectALSAChannelMap - Selected ALSA map "FL FR"
22:17:33.876 T:140523198080768 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
22:17:33.876 T:140523198080768 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
22:17:33.876 T:140523198080768 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 1024, bufferSize 9600
22:17:33.876 T:140523198080768 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 1024, bufferSize 9216
22:17:33.876 T:140523198080768 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 192 ms
22:17:33.876 T:140523198080768 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
22:17:33.876 T:140523198080768 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
22:17:33.877 T:140523198080768 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)
22:17:33.877 T:140523198080768 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
22:17:33.877 T:140523198080768 DEBUG: Output Device : Default (HDA Intel PCH ALC283 Analog)
22:17:33.877 T:140523198080768 DEBUG: Sample Rate : 48000
22:17:33.877 T:140523198080768 DEBUG: Sample Format : AE_FMT_S32NE
22:17:33.877 T:140523198080768 DEBUG: Channel Count : 2
22:17:33.877 T:140523198080768 DEBUG: Channel Layout: FL,FR
22:17:33.877 T:140523198080768 DEBUG: Frames : 1024
22:17:33.877 T:140523198080768 DEBUG: Frame Size : 8
22:17:33.879 T:140523189688064 DEBUG: Thread RemoteControl start, auto delete: false
22:17:33.879 T:140523189688064 INFO: LIRC Process: using: /run/lirc/lircd
22:17:33.879 T:140523189688064 INFO: LIRC Connect: successfully started
22:17:33.879 T:140523189688064 DEBUG: Thread RemoteControl 140523189688064 terminating
22:17:33.883 T:140523296773376 INFO: CKeyboardLayoutManager: loading keyboard layouts from special://xbmc/system/keyboardlayouts...
22:17:33.885 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Arabic QWERTY" successfully loaded
22:17:33.885 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian ЯВЕРТЪ" successfully loaded
22:17:33.885 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian АБВ" successfully loaded
22:17:33.885 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BasePY" successfully loaded
22:17:33.885 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BaiduPY" successfully loaded
22:17:33.886 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Croatian QWERTY" successfully loaded
22:17:33.886 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Czech QWERTZ" successfully loaded
22:17:33.886 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Danish QWERTY" successfully loaded
22:17:33.886 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "English QWERTY" successfully loaded
22:17:33.886 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "English AZERTY" successfully loaded
22:17:33.886 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "English ABC" successfully loaded
22:17:33.887 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "French AZERTY" successfully loaded
22:17:33.887 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "German QWERTZ" successfully loaded
22:17:33.887 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "German ABC" successfully loaded
22:17:33.887 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Greek QWERTY" successfully loaded
22:17:33.887 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew QWERTY" successfully loaded
22:17:33.887 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew ABC" successfully loaded
22:17:33.887 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Hungarian QWERTZ" successfully loaded
22:17:33.888 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Italian QWERTY" successfully loaded
22:17:33.888 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Korean ㄱㄴㄷ" successfully loaded
22:17:33.888 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian AZERTY" successfully loaded
22:17:33.888 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian QWERTY" successfully loaded
22:17:33.888 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Norwegian QWERTY" successfully loaded
22:17:33.888 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Polish QWERTY" successfully loaded
22:17:33.889 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Portuguese (Portugal) QWERTY" successfully loaded
22:17:33.889 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Romanian QWERTY" successfully loaded
22:17:33.889 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian ЙЦУКЕН" successfully loaded
22:17:33.889 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian АБВ" successfully loaded
22:17:33.889 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Silesian QWERTY" successfully loaded
22:17:33.889 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTZ" successfully loaded
22:17:33.890 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTY" successfully loaded
22:17:33.890 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak ABC" successfully loaded
22:17:33.890 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Spanish QWERTY" successfully loaded
22:17:33.890 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Swedish QWERTY" successfully loaded
22:17:33.891 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Turkish QWERTY" successfully loaded
22:17:33.891 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian ЙЦУКЕН" successfully loaded
22:17:33.891 T:140523296773376 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian АБВ" successfully loaded
22:17:33.891 T:140523296773376 DEBUG: UDisks: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UDisks was not provided by any .service files
22:17:33.892 T:140523296773376 DEBUG: DeviceKit.Disks: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.DeviceKit.Disks was not provided by any .service files
22:17:33.892 T:140523296773376 DEBUG: Selected UDev as storage provider
22:17:33.896 T:140523296773376 INFO: Available videomodes (xrandr):
22:17:33.896 T:140523296773376 INFO: Output 'HDMI1' has 22 modes
22:17:33.896 T:140523296773376 INFO: ID:0x48 Name:1920x1080 Refresh:60.000000 Width:1920 Height:1080
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:17:33.896 T:140523296773376 INFO: ID:0x102 Name:1920x1080 Refresh:50.000000 Width:1920 Height:1080
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:17:33.896 T:140523296773376 INFO: ID:0x103 Name:1920x1080 Refresh:59.940201 Width:1920 Height:1080
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:17:33.896 T:140523296773376 INFO: ID:0x106 Name:1920x1080 Refresh:24.000000 Width:1920 Height:1080
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:17:33.896 T:140523296773376 INFO: ID:0x108 Name:1920x1080 Refresh:23.976080 Width:1920 Height:1080
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:17:33.896 T:140523296773376 INFO: ID:0x104 Name:1920x1080i Refresh:30.000000 Width:1920 Height:1080
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:17:33.896 T:140523296773376 INFO: ID:0x105 Name:1920x1080i Refresh:25.000000 Width:1920 Height:1080
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:17:33.896 T:140523296773376 INFO: ID:0x107 Name:1920x1080i Refresh:29.970100 Width:1920 Height:1080
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:17:33.896 T:140523296773376 INFO: ID:0x109 Name:1280x720 Refresh:60.000000 Width:1280 Height:720
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:17:33.896 T:140523296773376 INFO: ID:0x10a Name:1280x720 Refresh:50.000000 Width:1280 Height:720
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:17:33.896 T:140523296773376 INFO: ID:0x10b Name:1280x720 Refresh:59.940201 Width:1280 Height:720
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:17:33.896 T:140523296773376 INFO: ID:0x10c Name:1440x576 Refresh:50.000000 Width:1440 Height:576
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 0.711111
22:17:33.896 T:140523296773376 INFO: ID:0x10d Name:1440x480 Refresh:60.000000 Width:1440 Height:480
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 0.592593
22:17:33.896 T:140523296773376 INFO: ID:0x10e Name:1440x480 Refresh:59.940060 Width:1440 Height:480
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 0.592593
22:17:33.896 T:140523296773376 INFO: ID:0x10f Name:720x576 Refresh:50.000000 Width:720 Height:576
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.422222
22:17:33.896 T:140523296773376 INFO: ID:0x110 Name:720x576i Refresh:25.000000 Width:720 Height:576
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.422222
22:17:33.896 T:140523296773376 INFO: ID:0x111 Name:720x480 Refresh:60.000000 Width:720 Height:480
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.185185
22:17:33.896 T:140523296773376 INFO: ID:0x112 Name:720x480 Refresh:59.940060 Width:720 Height:480
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.185185
22:17:33.896 T:140523296773376 INFO: ID:0x113 Name:720x480i Refresh:30.001110 Width:720 Height:480
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.185185
22:17:33.896 T:140523296773376 INFO: ID:0x114 Name:720x480i Refresh:29.970030 Width:720 Height:480
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.185185
22:17:33.896 T:140523296773376 INFO: ID:0x115 Name:640x480 Refresh:60.000000 Width:640 Height:480
22:17:33.896 T:140523296773376 INFO: Pixel Ratio: 1.333333
22:17:33.896 T:140523296773376 INFO: ID:0x116 Name:640x480 Refresh:59.940479 Width:640 Height:480
22:17:33.897 T:140523296773376 INFO: Pixel Ratio: 1.333333
22:17:33.897 T:140523296773376 NOTICE: Checking resolution 16
22:17:33.906 T:140523296773376 DEBUG: Window Manager Name: Fluxbox
22:17:33.933 T:140523296773376 NOTICE: Using visual 0x20
22:17:33.945 T:140523296773376 INFO: GL: Maximum texture width: 8192
22:17:33.945 T:140523296773376 DEBUG: EGL_EXTENSIONS: EGL_CHROMIUM_sync_control EGL_EXT_create_context_robustness EGL_EXT_image_dma_buf_import EGL_KHR_create_context EGL_KHR_fence_sync EGL_KHR_get_all_proc_addresses EGL_KHR_gl_renderbuffer_image EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_3D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_KHR_no_config_context EGL_KHR_reusable_sync EGL_KHR_surfaceless_context EGL_KHR_wait_sync EGL_MESA_configless_context EGL_MESA_drm_image EGL_MESA_image_dma_buf_export EGL_NOK_swap_region EGL_NOK_texture_from_pixmap EGL_NV_post_sub_buffer
22:17:33.945 T:140523296773376 NOTICE: GL_VENDOR = Intel Open Source Technology Center
22:17:33.945 T:140523296773376 NOTICE: GL_RENDERER = Mesa DRI Intel(R) HD Graphics 520 (Skylake GT2)
22:17:33.945 T:140523296773376 NOTICE: GL_VERSION = 3.0 Mesa 13.0.3
22:17:33.945 T:140523296773376 NOTICE: GL_SHADING_LANGUAGE_VERSION = 1.30
22:17:33.945 T:140523296773376 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_ARB_copy_buffer GL_ARB_depth_buffer_float GL_ARB_draw_instanced GL_ARB_half_float_vertex GL_ARB_instanced_arrays GL_ARB_map_buffer_range GL_ARB_texture_rg GL_ARB_texture_swizzle GL_ARB_vertex_array_bgra GL_EXT_texture_swizzle GL_EXT_vertex_array_bgra GL_NV_conditional_render GL_AMD_conservative_depth GL_AMD_draw_buffers_blend GL_AMD_seamless_cubemap_per_texture GL_AMD_shader_stencil_export GL_ARB_ES2_compatibility GL_ARB_blend_func_extended GL_ARB_debug_output GL_ARB_draw_buffers_blend GL_ARB_draw_elements_base_vertex GL_ARB_explicit_attrib_location GL_ARB_fragment_coord_conventions GL_ARB_provoking_vertex GL_ARB_sample_shading GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_shader_stencil_export GL_ARB_shader_texture_lod GL_ARB_texture_cube_map_array GL_ARB_texture_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_NV_texture_barrier GL_ARB_get_program_binary GL_ARB_robustness GL_ARB_separate_shader_objects GL_ARB_shader_bit_encoding GL_ARB_shader_precision 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_shader_image_load_store 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_arrays_of_arrays GL_ARB_clear_buffer_object GL_ARB_compute_shader GL_ARB_copy_image GL_ARB_explicit_uniform_location GL_ARB_framebuffer_no_attachments GL_ARB_invalidate_subdata GL_ARB_program_interface_query GL_ARB_robust_buffer_access_behavior GL_ARB_shader_image_size GL_ARB_shader_storage_buffer_object GL_ARB_stencil_texturing GL_ARB_texture_query_levels GL_ARB_texture_storage_multisample GL_ARB_texture_view GL_ARB_vertex_attrib_binding GL_KHR_debug GL_KHR_robustness GL_KHR_texture_compression_astc_ldr GL_ARB_buffer_storage GL_ARB_clear_texture GL_ARB_internalformat_query2 GL_ARB_multi_bind GL_ARB_query_buffer_object GL_ARB_seamless_cubemap_per_texture GL_ARB_shader_draw_parameters GL_ARB_texture_mirror_clamp_to_edge GL_ARB_texture_stencil8 GL_ARB_vertex_type_10f_11f_11f_rev GL_EXT_shader_integer_mix GL_ARB_clip_control GL_ARB_conditional_render_inverted GL_ARB_cull_distance GL_ARB_derivative_control GL_ARB_get_texture_sub_image GL_ARB_pipeline_statistics_query GL_ARB_shader_texture_image_samples GL_ARB_texture_barrier GL_EXT_polygon_offset_clamp GL_KHR_blend_equation_advanced GL_KHR_blend_equation_advanced_coherent GL_KHR_context_flush_control GL_KHR_robust_buffer_access_behavior GL_ARB_shader_atomic_counter_ops GL_ARB_shader_clock GL_EXT_shader_samples_identical GL_KHR_texture_compression_astc_sliced_3d GL_MESA_shader_integer_functions
22:17:33.946 T:140523296773376 INFO: GL: Maximum texture width: 8192
22:17:34.024 T:140523296773376 INFO: GL: Enabling VSYNC
22:17:34.028 T:140523296773376 INFO: load keymapping
22:17:34.029 T:140523296773376 INFO: Loading special://xbmc/system/keymaps/appcommand.xml
22:17:34.029 T:140523296773376 INFO: Loading special://xbmc/system/keymaps/customcontroller.AppleRemote.xml
22:17:34.030 T:140523296773376 INFO: Loading special://xbmc/system/keymaps/customcontroller.Harmony.xml
22:17:34.031 T:140523296773376 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
22:17:34.033 T:140523296773376 INFO: Loading special://xbmc/system/keymaps/joystick.xml
22:17:34.034 T:140523296773376 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
22:17:34.037 T:140523296773376 INFO: Loading special://xbmc/system/keymaps/mouse.xml
22:17:34.037 T:140523296773376 INFO: Loading special://xbmc/system/keymaps/remote.xml
22:17:34.040 T:140523296773376 INFO: Loading special://xbmc/system/keymaps/touchscreen.xml
22:17:34.041 T:140523296773376 INFO: Loading special://xbmc/system/Lircmap.xml
22:17:34.042 T:140523296773376 INFO: * Adding remote mapping for device 'mceusb'
22:17:34.042 T:140523296773376 INFO: * Adding remote mapping for device 'XboxDVDDongle'
22:17:34.042 T:140523296773376 INFO: * Adding remote mapping for device 'Microsoft_Xbox'
22:17:34.042 T:140523296773376 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
22:17:34.042 T:140523296773376 INFO: * Adding remote mapping for device 'anysee'
22:17:34.042 T:140523296773376 INFO: * Adding remote mapping for device 'iMON-PAD'
22:17:34.042 T:140523296773376 INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
22:17:34.042 T:140523296773376 INFO: * Adding remote mapping for device 'MCE_via_iMON'
22:17:34.042 T:140523296773376 INFO: * Adding remote mapping for device 'TwinHanRemote'
22:17:34.042 T:140523296773376 INFO: * Adding remote mapping for device 'linux-input-layer'
22:17:34.042 T:140523296773376 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
22:17:34.042 T:140523296773376 INFO: * Adding remote mapping for device 'mediacenter'
22:17:34.042 T:140523296773376 INFO: * Adding remote mapping for device 'devinput'
22:17:34.042 T:140523296773376 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
22:17:34.042 T:140523296773376 INFO: GUI format 1920x1080, Display 1920x1080@ 60.00 - Full Screen
22:17:34.042 T:140523296773376 DEBUG: guilib: Fill viewport on change for solving rendering passes
22:17:34.043 T:140523296773376 INFO: CLangInfo: loading resource.language.en_gb language information...
22:17:34.044 T:140523296773376 DEBUG: trying to set locale to en_DE.UTF-8
22:17:34.053 T:140523296773376 INFO: global locale set to C
22:17:34.053 T:140523296773376 INFO: CLangInfo: loading resource.language.en_gb language strings...
22:17:34.063 T:140523296773376 DEBUG: LocalizeStrings: loaded 3769 strings from file resource://resource.language.en_gb/strings.po
22:17:34.066 T:140523296773376 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.xbmc.builtin.aac/resources/language/English/strings.po
22:17:34.066 T:140523296773376 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.xbmc.builtin.wma/resources/language/English/strings.po
22:17:34.067 T:140523296773376 DEBUG: LocalizeStrings: loaded 18 strings from file /usr/share/kodi/addons/game.controller.default/resources/language/resource.language.en_gb/strings.po
22:17:34.068 T:140523296773376 DEBUG: LocalizeStrings: loaded 13 strings from file /usr/share/kodi/addons/metadata.album.universal/resources/language/English/strings.po
22:17:34.069 T:140523296773376 DEBUG: LocalizeStrings: loaded 26 strings from file /usr/share/kodi/addons/metadata.artists.universal/resources/language/English/strings.po
22:17:34.070 T:140523296773376 DEBUG: LocalizeStrings: loaded 7 strings from file /usr/share/kodi/addons/metadata.themoviedb.org/resources/language/English/strings.po
22:17:34.070 T:140523296773376 DEBUG: LocalizeStrings: loaded 7 strings from file /usr/share/kodi/addons/metadata.tvdb.com/resources/language/English/strings.po
22:17:34.072 T:140523296773376 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/screensaver.xbmc.builtin.dim/resources/language/resource.language.en_gb/strings.po
22:17:34.074 T:140523296773376 DEBUG: LocalizeStrings: loaded 200 strings from file /usr/share/kodi/addons/service.libreelec.settings/resources/language/English/strings.po
22:17:34.076 T:140523296773376 DEBUG: LocalizeStrings: loaded 61 strings from file /storage/.kodi/addons/metadata.universal/resources/language/English/strings.po
22:17:34.077 T:140523296773376 DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
22:17:34.077 T:140523296773376 DEBUG: LoadMappings - loaded node "CEC Adapter"
22:17:34.077 T:140523296773376 DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
22:17:34.077 T:140523296773376 DEBUG: LoadMappings - loaded node "iMON HID device"
22:17:34.077 T:140523296773376 DEBUG: LoadMappings - loaded node "WETEK Play remote"
22:17:34.077 T:140523296773376 DEBUG: LoadMappings - loaded node ""
22:17:34.077 T:140523296773376 DEBUG: CPeripheralBusUSB - initialised udev monitor
22:17:34.077 T:140523296773376 DEBUG: SECTION:LoadDLL(libcec.so.4)
22:17:34.078 T:140523296773376 DEBUG: Loading: libcec.so.4
22:17:34.085 T:140523296773376 DEBUG: Add-on bus: Registering add-on peripheral.joystick
22:17:34.085 T:140523296773376 DEBUG: PERIPHERAL - CreateAddon - creating peripheral add-on instance 'Joystick Support'
22:17:34.085 T:140523296773376 DEBUG: ADDON: Dll Initializing - Joystick Support
22:17:34.085 T:140523296773376 DEBUG: SECTION:LoadDLL(/usr/lib/kodi/addons/peripheral.joystick/peripheral.joystick.so.1.2.1)
22:17:34.085 T:140523296773376 DEBUG: Loading: /usr/lib/kodi/addons/peripheral.joystick/peripheral.joystick.so.1.2.1
22:17:34.093 T:140523296773376 DEBUG: AddOnLog: Joystick Support: Loaded 14 joystick families with 97 total joysticks
22:17:34.093 T:140523296773376 DEBUG: Calling TransferSettings for: Joystick Support
22:17:34.093 T:140523062449920 DEBUG: Thread PeripBusUSBUdev start, auto delete: false
22:17:34.093 T:140523054057216 DEBUG: Thread PeripBusCEC start, auto delete: false
22:17:34.093 T:140523045664512 DEBUG: Thread PeripBusAddon start, auto delete: false
22:17:34.093 T:140523296773376 DEBUG: SECTION:LoadDLL(libcurl.so.4)
22:17:34.093 T:140523037271808 DEBUG: Thread PeripEventScanner start, auto delete: false
22:17:34.094 T:140523296773376 DEBUG: Loading: libcurl.so.4
22:17:34.100 T:140523075532544 NOTICE: Running database version Addons27
22:17:34.101 T:140523075532544 DEBUG: Initialize, updating databases...
22:17:34.101 T:140523075532544 NOTICE: Running database version ViewModes6
22:17:34.131 T:140523075532544 NOTICE: Running database version Textures13
22:17:34.133 T:140523075532544 NOTICE: Running database version MyMusic60
22:17:34.159 T:140523062449920 DEBUG: GetMappingForDevice - device (2548:1002) mapped to Pulse-Eight CEC Adapter (type = cec)
22:17:34.160 T:140523054057216 DEBUG: GetMappingForDevice - device (2548:1002) mapped to CEC Adapter (type = cec)
22:17:34.165 T:140523054057216 DEBUG: InitialiseFeature - using libCEC v4.0.1
22:17:34.165 T:140523054057216 DEBUG: Initialise - initialised peripheral on '/dev/ttyACM0' with 2 features and 0 sub devices
22:17:34.165 T:140523054057216 NOTICE: Register - new cec device registered on cec->/dev/ttyACM0: CEC Adapter (2548:1002)
22:17:34.165 T:140523017971456 DEBUG: Thread CECAdapter start, auto delete: false
22:17:34.165 T:140523017971456 DEBUG: OpenConnection - opening a connection to the CEC adapter: /dev/ttyACM0
22:17:34.273 T:140523075532544 NOTICE: Running database version MyVideos107
22:17:34.278 T:140523075532544 NOTICE: Running database version TV29
22:17:34.279 T:140523075532544 NOTICE: Running database version Epg11
22:17:34.280 T:140523075532544 DEBUG: Initialize, updating databases... DONE
22:17:34.285 T:140523296773376 NOTICE: start dvd mediatype detection
22:17:34.286 T:140523277264640 DEBUG: Thread DetectDVDMedia start, auto delete: false
22:17:34.286 T:140523277264640 DEBUG: Compiled with libcdio Version 0.94
22:17:34.286 T:140523296773376 DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
22:17:34.287 T:140523277264640 DEBUG: Thread DetectDVDMedia 140523277264640 terminating
22:17:34.316 T:140523296757504 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
22:17:34.317 T:140523296757504 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
22:17:34.323 T:140523296773376 INFO: Unloading old skin ...
22:17:34.324 T:140523296773376 INFO: load skin from: /usr/share/kodi/addons/skin.estuary (version: 1.9.10)
22:17:34.324 T:140523296773376 INFO: load fonts for skin...
22:17:34.328 T:140523296773376 INFO: Loading fonts from /usr/share/kodi/addons/skin.estuary/xml/Font.xml
22:17:34.351 T:140523296773376 DEBUG: LocalizeStrings: loaded 132 strings from file /usr/share/kodi/addons/skin.estuary/language/resource.language.en_gb/strings.po
22:17:34.351 T:140523296773376 INFO: Loading skin includes from /usr/share/kodi/addons/skin.estuary/xml/Includes.xml
22:17:34.376 T:140523296773376 INFO: load new skin...
22:17:34.376 T:140523296773376 INFO: Loading user windows, path /usr/share/kodi/addons/skin.estuary/xml
22:17:34.380 T:140523296773376 DEBUG: Load Skin XML: 3.82ms
22:17:34.380 T:140523296773376 INFO: initialize new skin...
22:17:34.380 T:140523296773376 DEBUG: guilib: Fill viewport on change for solving rendering passes
22:17:34.380 T:140523296773376 INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
22:17:34.382 T:140523296773376 DEBUG: OpenBundle - Opened bundle /usr/share/kodi/addons/skin.estuary/media/Textures.xbt
22:17:34.383 T:140523296773376 INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
22:17:34.385 T:140523296773376 INFO: Loading skin file: DialogNotification.xml, load type: LOAD_ON_GUI_INIT
22:17:34.385 T:140523296773376 INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
22:17:34.389 T:140523296773376 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
22:17:34.389 T:140523296773376 INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
22:17:34.391 T:140523296773376 INFO: skin loaded...
22:17:34.391 T:140523296773376 DEBUG: Activating window ID: 12997
22:17:34.391 T:140523296773376 DEBUG: ------ Window Init () ------
22:17:34.391 T:140523296773376 INFO: load splash image: /usr/share/kodi/media/Splash.png
22:17:34.399 T:140523296773376 DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
22:17:34.399 T:140523296773376 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
22:17:34.399 T:140523296773376 INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
22:17:34.399 T:140523296773376 INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
22:17:34.413 T:140523296773376 INFO: JSONRPC v8.0.0: Successfully initialized
22:17:34.413 T:140523296773376 DEBUG: ADDON: Starting service addons.
22:17:34.414 T:140523296773376 DEBUG: Activating window ID: 12999
22:17:34.414 T:140523296773376 DEBUG: ------ Window Init (Startup.xml) ------
22:17:34.414 T:140523296773376 INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
22:17:34.414 T:140523296773376 DEBUG: Activating window ID: 10000
22:17:34.414 T:140523296773376 DEBUG: ------ Window Deinit (Startup.xml) ------
22:17:34.414 T:140523296773376 DEBUG: ------ Window Init (Home.xml) ------
22:17:34.414 T:140523296773376 INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
22:17:34.478 T:140523296773376 DEBUG: CDirectoryProvider[library://video/movies/]: refreshing..
22:17:34.478 T:140523296773376 DEBUG: CDirectoryProvider[special://skin/playlists/inprogress_movies.xsp]: refreshing..
22:17:34.478 T:140523296773376 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_movies.xsp]: refreshing..
22:17:34.478 T:140523296773376 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_movies.xsp]: refreshing..
22:17:34.478 T:140523296773376 DEBUG: CDirectoryProvider[special://skin/playlists/random_movies.xsp]: refreshing..
22:17:34.478 T:140523296773376 DEBUG: CDirectoryProvider[videodb://movies/genres/]: refreshing..
22:17:34.478 T:140523296773376 DEBUG: CDirectoryProvider[videodb://movies/sets/]: refreshing..
22:17:34.478 T:140522538661632 DEBUG: Thread JobWorker start, auto delete: true
22:17:34.478 T:140523296773376 DEBUG: CDirectoryProvider[library://video/tvshows/]: refreshing..
22:17:34.478 T:140523296773376 DEBUG: CDirectoryProvider[videodb://inprogresstvshows]: refreshing..
22:17:34.478 T:140522530268928 DEBUG: Thread JobWorker start, auto delete: true
22:17:34.478 T:140523296773376 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_episodes.xsp]: refreshing..
22:17:34.478 T:140523296773376 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_tvshows.xsp]: refreshing..
22:17:34.478 T:140523296773376 DEBUG: CDirectoryProvider[videodb://tvshows/genres/]: refreshing..
22:17:34.478 T:140523296773376 DEBUG: CDirectoryProvider[videodb://tvshows/studios/]: refreshing..
22:17:34.482 T:140523296773376 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing..
22:17:34.482 T:140523296773376 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing..
22:17:34.482 T:140523296773376 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing..
22:17:34.482 T:140523296773376 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing..
22:17:34.482 T:140523296773376 DEBUG: CDirectoryProvider[library://video/]: refreshing..
22:17:34.482 T:140523296773376 DEBUG: CDirectoryProvider[sources://video/]: refreshing..
22:17:34.482 T:140523296773376 DEBUG: CDirectoryProvider[special://videoplaylists/]: refreshing..
22:17:34.482 T:140523296773376 DEBUG: CDirectoryProvider[favourites://]: refreshing..
22:17:34.482 T:140523296773376 DEBUG: CDirectoryProvider[sources://pictures/]: refreshing..
22:17:34.487 T:140523296773376 DEBUG: ContextMenuManager: addon menus reloaded.
22:17:34.487 T:140523296773376 INFO: removing tempfiles
22:17:34.488 T:140523296773376 DEBUG: ADDON: Starting service addons.
22:17:34.489 T:140522538661632 DEBUG: RunQuery took 1 ms for 3 items query: select * from movie_view WHERE (movie_view.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
22:17:34.490 T:140522219489024 DEBUG: Thread LanguageInvoker start, auto delete: false
22:17:34.490 T:140522219489024 INFO: initializing python engine.
22:17:34.496 T:140523296773376 DEBUG: CRepositoryUpdater: previous update at 30-03-2017 1:09:48, next at 31-03-2017 1:09:48
22:17:34.496 T:140523296773376 NOTICE: initialize done
22:17:34.496 T:140523296773376 NOTICE: Running the application...
22:17:34.496 T:140523296773376 DEBUG: no profile autoexec.py (/storage/.kodi/userdata/autoexec.py) found, skipping
22:17:34.496 T:140523296773376 DEBUG: NetworkMessage - Starting network services
22:17:34.497 T:140522240661248 DEBUG: Thread Timer start, auto delete: false
22:17:34.503 T:140523296773376 DEBUG: CZeroconfAvahi::clientCallback: client is up and running
22:17:34.503 T:140523296773376 NOTICE: starting zeroconf publishing
22:17:34.503 T:140523296773376 NOTICE: CWebServer[8080]: Started
22:17:34.503 T:140523296773376 NOTICE: starting upnp client
22:17:34.505 T:140523296773376 DEBUG: GetFirstConnectedInterface no connected interface found - requery list
22:17:34.514 T:140522530268928 DEBUG: RunQuery took 26 ms for 923 items query: select * from movie_view WHERE ((movie_view.dateAdded > '1900-01-01')) AND ((movie_view.playCount IS NULL OR movie_view.playCount < 1))
22:17:34.526 T:140523075532544 DEBUG: RunQuery took 24 ms for 923 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount = 0))
22:17:34.527 T:140522538661632 DEBUG: RunQuery took 28 ms for 973 items query: select * from movie_view
22:17:34.529 T:140523296773376 INFO: JSONRPC Server: Successfully initialized
22:17:34.529 T:140521708177152 DEBUG: Thread EventServer start, auto delete: false
22:17:34.529 T:140521708177152 NOTICE: ES: Starting UDP Event server on port 9777
22:17:34.529 T:140523296773376 DEBUG: GetFirstConnectedInterface no connected interface found - requery list
22:17:34.529 T:140521708177152 NOTICE: UDP: Listening on port 9777 (ipv6 : true)
22:17:34.529 T:140523296773376 DEBUG: SECTION:LoadDLL(libshairplay.so.0)
22:17:34.529 T:140523296773376 DEBUG: Loading: libshairplay.so.0
22:17:34.530 T:140521699784448 DEBUG: Thread TCPServer start, auto delete: false
22:17:34.531 T:140522219489024 DEBUG: CPythonInvoker(0, /usr/share/kodi/addons/service.libreelec.settings/service.py): start processing
22:17:34.531 T:140523296773376 DEBUG: GetFirstConnectedInterface no connected interface found - requery list
22:17:34.531 T:140523296773376 INFO: AIRPLAY: Cleaning up photoassetcache
22:17:34.534 T:140523296773376 INFO: AIRPLAY Server: Successfully initialized
22:17:34.534 T:140523296773376 DEBUG: GetFirstConnectedInterface no connected interface found - requery list
22:17:34.538 T:140521682999040 DEBUG: Thread AirPlayServer start, auto delete: false
22:17:34.555 T:140522219489024 DEBUG: -->Python Interpreter Initialized<--
22:17:34.555 T:140522219489024 DEBUG: CPythonInvoker(0, /usr/share/kodi/addons/service.libreelec.settings/service.py): the source file to load is "/usr/share/kodi/addons/service.libreelec.settings/service.py"
22:17:34.555 T:140522219489024 DEBUG: CPythonInvoker(0, /usr/share/kodi/addons/service.libreelec.settings/service.py): setting the Python path to /usr/share/kodi/addons/service.libreelec.settings:/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:/usr/lib/python2.7/site-packages/gtk-2.0
22:17:34.555 T:140522219489024 DEBUG: CPythonInvoker(0, /usr/share/kodi/addons/service.libreelec.settings/service.py): entering source directory /usr/share/kodi/addons/service.libreelec.settings
22:17:34.555 T:140522219489024 DEBUG: CPythonInvoker(0, /usr/share/kodi/addons/service.libreelec.settings/service.py): instantiating addon using automatically obtained id of "service.libreelec.settings" dependent on version 2.1.0 of the xbmc.python api
22:17:34.566 T:140522530268928 DEBUG: RunQuery took 16 ms for 23 items query: SELECT genre.genre_id, genre.name, count(1), count(files.playCount) FROM genre JOIN genre_link ON genre.genre_id = genre_link.genre_id JOIN movie_view ON genre_link.media_id = movie_view.idMovie AND genre_link.media_type='movie' JOIN files ON files.idFile = movie_view.idFile GROUP BY genre.genre_id
22:17:34.570 T:140522538661632 DEBUG: RunQuery took 14 ms for 356 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet ORDER BY sets.idSet
22:17:34.585 T:140522530268928 DEBUG: RunQuery took 7 ms for 0 items query: SELECT * FROM tvshow_view WHERE watchedCount != 0 AND totalCount != watchedCount ORDER BY c00
22:17:34.598 T:140522530268928 DEBUG: RunQuery took 6 ms for 52 items query: SELECT * FROM tvshow_view WHERE ((tvshow_view.watchedcount = 0)) AND ((tvshow_view.totalCount > 0))
22:17:34.625 T:140522530268928 DEBUG: RunQuery took 7 ms for 18 items query: SELECT genre.genre_id, genre.name FROM genre JOIN genre_link ON genre.genre_id = genre_link.genre_id JOIN tvshow_view ON genre_link.media_id = tvshow_view.idShow AND genre_link.media_type='tvshow' GROUP BY genre.genre_id
22:17:34.633 T:140522538661632 DEBUG: RunQuery took 8 ms for 33 items query: SELECT studio.studio_id, studio.name FROM studio JOIN studio_link ON studio.studio_id = studio_link.studio_id JOIN tvshow_view ON studio_link.media_id = tvshow_view.idShow AND studio_link.media_type='tvshow' GROUP BY studio.studio_id
22:17:34.638 T:140523075532544 DEBUG: RunQuery took 50 ms for 2815 items query: select * from episode_view WHERE ((episode_view.dateAdded > '1900-01-01')) AND ((episode_view.playCount IS NULL OR episode_view.playCount < 1))
22:17:34.644 T:140522530268928 DEBUG: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
22:17:34.644 T:140522530268928 DEBUG: Getting Directory (special://profile/playlists/video)
22:17:34.645 T:140522530268928 DEBUG: Getting Directory (special://profile/playlists/mixed)
22:17:34.645 T:140522530268928 DEBUG: CMultiPathDirectory::MergeItems, items = 0
22:17:34.645 T:140522530268928 DEBUG: CFavourites::Load - no system favourites found, skipping
22:17:34.647 T:140522530268928 WARNING: CreateLoader - unsupported protocol(activatewindow(10025,"smb) in activatewindow(10025,"smb://SERVER/series/autoDL/",return).tbn
22:17:34.664 T:140522538661632 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webserver type: _http._tcp name:Kodi (LibreELEC) port:8080
22:17:34.664 T:140522538661632 DEBUG: CZeroconfAvahi::addService() named: Kodi (LibreELEC) type: _http._tcp port:8080
22:17:34.666 T:140522232268544 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
22:17:34.666 T:140522538661632 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-http type: _xbmc-jsonrpc-h._tcp name:Kodi (LibreELEC) port:8080
22:17:34.666 T:140522538661632 DEBUG: CZeroconfAvahi::addService() named: Kodi (LibreELEC) type: _xbmc-jsonrpc-h._tcp port:8080
22:17:34.667 T:140522232268544 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
22:17:34.667 T:140522538661632 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:Kodi (LibreELEC) port:9090
22:17:34.667 T:140522538661632 DEBUG: CZeroconfAvahi::addService() named: Kodi (LibreELEC) type: _xbmc-jsonrpc._tcp port:9090
22:17:34.668 T:140522232268544 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
22:17:34.668 T:140522538661632 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:Kodi (LibreELEC) port:9777
22:17:34.668 T:140522538661632 DEBUG: CZeroconfAvahi::addService() named: Kodi (LibreELEC) type: _xbmc-events._udp port:9777
22:17:34.670 T:140522232268544 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
22:17:34.670 T:140522538661632 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.airtunes type: _raop._tcp name:000102030405@Kodi (LibreELEC) port:36666
22:17:34.670 T:140522538661632 DEBUG: CZeroconfAvahi::addService() named: 000102030405@Kodi (LibreELEC) type: _raop._tcp port:36666
22:17:34.671 T:140522232268544 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
22:17:34.671 T:140522538661632 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.airplay type: _airplay._tcp name:Kodi (LibreELEC) port:36667
22:17:34.671 T:140522538661632 DEBUG: CZeroconfAvahi::addService() named: Kodi (LibreELEC) type: _airplay._tcp port:36667
22:17:34.672 T:140522232268544 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
22:17:34.674 T:140522001790720 DEBUG: Thread JobWorker start, auto delete: true
22:17:34.681 T:140523296773376 DEBUG: ------ Window Init () ------
22:17:34.683 T:140522530268928 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
22:17:34.687 T:140522530268928 DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.*, songartistview.* FROM (SELECT idAlbum FROM album ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum = recentalbums.idAlbum JOIN songartistview ON songview.idSong = songartistview.idSong ORDER BY songview.idAlbum DESC, songview.idSong, songartistview.idRole, songartistview.iOrder
22:17:34.687 T:140522530268928 DEBUG: GetRecentlyAddedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM album WHERE strAlbum != '' ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN albumview ON albumview.idAlbum = recentalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY albumview.idAlbum desc, albumartistview.iOrder
22:17:34.688 T:140522530268928 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
22:17:34.710 T:140522530268928 DEBUG: RunQuery took 12 ms for 10 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 10
22:17:34.727 T:140523296773376 DEBUG: ------ Window Init (DialogNotification.xml) ------
22:17:34.736 T:140522530268928 DEBUG: RunQuery took 22 ms for 10 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 10
22:17:34.741 T:140522530268928 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view ORDER BY dateAdded desc, idMVideo desc LIMIT 10
22:17:34.744 T:140522530268928 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
22:17:34.746 T:140522530268928 DEBUG: GetArtistsByWhere query: SELECT COUNT(DISTINCT artistview.idArtist) FROM artistview WHERE ((EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artistview.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '')) AND (artistview.strArtist <> 'Various artists')
22:17:34.794 T:140522219489024 DEBUG: ## LibreELEC Addon ## 0.8.9
22:17:34.795 T:140522219489024 WARNING: CSkinInfo: failed to load skin settings
22:17:35.004 T:140523296773376 DEBUG: LogindUPowerSyscall - Received unknown signal NameAcquired
22:17:35.018 T:140522219489024 ERROR: ## LibreELEC Addon ## oe::load_url(http://releases.libreelec.tv/releases.json) ## ERROR: (URLError(gaierror(-3, 'Temporary failure in name resolution'),))
22:17:35.019 T:140522219489024 ERROR: Traceback (most recent call last):
File "/home/chewitt/LibreELEC.80-official/build.LibreELEC-Generic.x86_64-8.0.0/LibreELEC-settings-5429575/.install_pkg/usr/share/kodi/addons/service.libreelec.settings/oe.py", line 271, in load_url
File "/usr/lib/python2.7/urllib2.py", line 154, in urlopen
File "/usr/lib/python2.7/urllib2.py", line 429, in open
File "/usr/lib/python2.7/urllib2.py", line 447, in _open
File "/usr/lib/python2.7/urllib2.py", line 407, in _call_chain
File "/usr/lib/python2.7/urllib2.py", line 1228, in http_open
File "/usr/lib/python2.7/urllib2.py", line 1198, in do_open
URLError:
22:17:35.019 T:140522219489024 INFO: ## LibreELEC Addon ## system::set_hostname ## LibreELEC
22:17:35.019 T:140522219489024 INFO: ## LibreELEC Addon ## system::set_keyboard_layout ## us,us -model pc105
22:17:35.057 T:140522219489024 INFO: ## LibreELEC Addon ## system::updateThread ## Started
22:17:35.058 T:140522219489024 INFO: ## LibreELEC Addon ## system::set_auto_update ## manual
22:17:35.058 T:140521330702080 ERROR: ## LibreELEC Addon ## oe::load_url(http://update.libreelec.tv/updates.php?i=871e651897d6bf86e865db3a753f110b&d=LibreELEC&pa=Generic.x86_64&v=8.0.0&l=1
) ## ERROR: (URLError(gaierror(-3, 'Temporary failure in name resolution'),))
22:17:35.058 T:140521330702080 ERROR: Traceback (most recent call last):
File "/home/chewitt/LibreELEC.80-official/build.LibreELEC-Generic.x86_64-8.0.0/LibreELEC-settings-5429575/.install_pkg/usr/share/kodi/addons/service.libreelec.settings/oe.py", line 271, in load_url
File "/usr/lib/python2.7/urllib2.py", line 154, in urlopen
File "/usr/lib/python2.7/urllib2.py", line 429, in open
File "/usr/lib/python2.7/urllib2.py", line 447, in _open
File "/usr/lib/python2.7/urllib2.py", line 407, in _call_chain
File "/usr/lib/python2.7/urllib2.py", line 1228, in http_open
File "/usr/lib/python2.7/urllib2.py", line 1198, in do_open
URLError:
22:17:35.058 T:140521330702080 ERROR: ## LibreELEC Addon ## system::check_updates_v2 ## ERROR: (TypeError('expected string or buffer',))
22:17:35.058 T:140521330702080 ERROR: Traceback (most recent call last):
File "/home/chewitt/LibreELEC.80-official/build.LibreELEC-Generic.x86_64-8.0.0/LibreELEC-settings-5429575/.install_pkg/usr/share/kodi/addons/service.libreelec.settings/resources/lib/modules/system.py", line 768, in check_updates_v2
File "/usr/lib/python2.7/json/__init__.py", line 339, in loads
File "/usr/lib/python2.7/json/decoder.py", line 364, in decode
TypeError: expected string or buffer
22:17:35.065 T:140521313916672 INFO: ## LibreELEC Addon ## _service_::run ## WAITING:
22:17:35.069 T:140521322309376 INFO: ## LibreELEC Addon ## xdbus Monitor started. ##
22:17:35.102 T:140523062449920 DEBUG: GetMappingForDevice - device (2548:1002) mapped to Pulse-Eight CEC Adapter (type = cec)
22:17:35.311 T:140523017971456 DEBUG: OpenConnection - connection to the CEC adapter opened
22:17:35.311 T:140523017971456 DEBUG: SetConfigurationFromLibCEC - settings updated by libCEC
22:17:35.311 T:140521305523968 DEBUG: Thread CECAdapterUpdate start, auto delete: false
22:17:35.559 T:140521305523968 DEBUG: UpdateMenuLanguage - using TV menu language is disabled
22:17:35.697 T:140523062449920 DEBUG: GetMappingForDevice - device (2548:1002) mapped to Pulse-Eight CEC Adapter (type = cec)
22:17:37.139 T:140521305523968 DEBUG: UpdateAudioSystemStatus - no CEC capable amplifier found
22:17:42.536 T:140523296773376 DEBUG: ------ Window Deinit (DialogNotification.xml) ------
22:18:05.492 T:140522538661632 DEBUG: Thread JobWorker 140522538661632 terminating (autodelete)
22:18:05.492 T:140522001790720 DEBUG: Thread JobWorker 140522001790720 terminating (autodelete)
22:18:05.494 T:140523075532544 DEBUG: Thread JobWorker 140523075532544 terminating (autodelete)
22:18:05.495 T:140522530268928 DEBUG: Thread JobWorker 140522530268928 terminating (autodelete)
22:18:06.874 T:140523026364160 DEBUG: PushCecKeypress - received key a7 duration 0
22:18:06.891 T:140523296773376 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
22:18:06.898 T:140522530268928 DEBUG: Thread JobWorker start, auto delete: true
22:18:06.913 T:140523296773376 DEBUG: Previous line repeats 3 times.
22:18:06.914 T:140523296773376 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultStudio.png'
22:18:07.151 T:140523026364160 DEBUG: Previous line repeats 5 times.
22:18:07.151 T:140523026364160 DEBUG: PushCecKeypress - received key a7 duration 277
22:18:07.523 T:140523026364160 DEBUG: PushCecKeypress - received key a8 duration 0
22:18:07.536 T:140523296773376 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
22:18:07.800 T:140523026364160 DEBUG: PushCecKeypress - received key a8 duration 278
22:18:08.424 T:140523026364160 DEBUG: PushCecKeypress - received key a9 duration 0
22:18:08.433 T:140523296773376 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left
22:18:08.702 T:140523026364160 DEBUG: PushCecKeypress - received key a9 duration 279
22:18:09.075 T:140523026364160 DEBUG: PushCecKeypress - received key a6 duration 0
22:18:09.107 T:140523296773376 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up
22:18:09.353 T:140523026364160 DEBUG: PushCecKeypress - received key a6 duration 278
22:18:09.678 T:140523026364160 DEBUG: PushCecKeypress - received key a8 duration 0
22:18:09.693 T:140523296773376 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
22:18:09.951 T:140523026364160 DEBUG: PushCecKeypress - received key a8 duration 272
22:18:10.377 T:140523026364160 DEBUG: PushCecKeypress - received key a7 duration 0
22:18:10.413 T:140523296773376 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
22:18:10.650 T:140523026364160 DEBUG: PushCecKeypress - received key a7 duration 273
22:18:10.928 T:140523026364160 DEBUG: PushCecKeypress - received key b duration 0
22:18:10.936 T:140523296773376 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
22:18:10.945 T:140523296773376 INFO: Loading skin file: DialogContextMenu.xml, load type: KEEP_IN_MEMORY
22:18:10.947 T:140523296773376 DEBUG: ------ Window Init (DialogContextMenu.xml) ------
22:18:11.149 T:140523026364160 DEBUG: PushCecKeypress - received key b duration 220
22:18:12.125 T:140523026364160 DEBUG: PushCecKeypress - received key b duration 0
22:18:12.147 T:140523296773376 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
22:18:12.398 T:140523026364160 DEBUG: PushCecKeypress - received key b duration 273
22:18:12.400 T:140523296773376 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------
22:18:12.408 T:140523296773376 DEBUG: Loading settings for smb://SERVER/movies/The Sting - 1973.m4v
22:18:12.414 T:140523296773376 DEBUG: CPlayerCoreFactory::GetPlayers(smb://SERVER/movies/The Sting - 1973.m4v)
22:18:12.414 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
22:18:12.414 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
22:18:12.414 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
22:18:12.414 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
22:18:12.414 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
22:18:12.414 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
22:18:12.414 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
22:18:12.414 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
22:18:12.414 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
22:18:12.414 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
22:18:12.414 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
22:18:12.414 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
22:18:12.414 T:140523296773376 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
22:18:12.414 T:140523296773376 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
22:18:12.414 T:140523296773376 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
22:18:12.414 T:140523296773376 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
22:18:12.414 T:140523296773376 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
22:18:12.416 T:140523296773376 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
22:18:12.416 T:140523296773376 NOTICE: VideoPlayer: Opening: smb://SERVER/movies/The Sting - 1973.m4v
22:18:12.416 T:140523296773376 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
22:18:12.416 T:140523296773376 DEBUG: LinuxRendererGL: Cleaning up GL resources
22:18:12.416 T:140523296773376 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
22:18:12.416 T:140521292936960 DEBUG: Thread VideoPlayer start, auto delete: false
22:18:12.417 T:140521292936960 NOTICE: Creating InputStream
22:18:12.657 T:140521292936960 DEBUG: CSMBFile::Open - opened smb://SERVER/movies/The Sting - 1973.m4v, fd=10000
22:18:12.657 T:140521292936960 DEBUG: ScanForExternalSubtitles: Searching for subtitles...
22:18:12.765 T:140521292936960 DEBUG: ScanForExternalSubtitles: END (total time: 108 ms)
22:18:12.766 T:140521292936960 NOTICE: Creating Demuxer
22:18:12.772 T:140521292936960 DEBUG: Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
22:18:12.916 T:140523296773376 DEBUG: ------ Window Init (DialogBusy.xml) ------
22:18:13.166 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: [mov,mp4,m4a,3gp,3g2,mj2] Protocol name not provided, cannot determine if input is local or a network protocol, buffers and access patterns cannot be configured optimally without knowing the protocol
22:18:13.241 T:140521292936960 DEBUG: Open - avformat_find_stream_info starting
22:18:13.263 T:140521292936960 DEBUG: Open - av_find_stream_info finished
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Input #0, mov,mp4,m4a,3gp,3g2,mj2, smb://SERVER/movies/The Sting - 1973.m4':
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: major_brand : mp42
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: minor_version : 512
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: compatible_brands: isomiso2avc1mp41
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: creation_time : 2017-03-26 08:19:45
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: title : The Sting 1973 1080p Blu-ray Remux AVC DTS-HD MA 5.1 - KRaLiMaRKo
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: encoder : HandBrake 0.10.2 2016021200
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Duration: 02:09:16.83, start: 0.000000, bitrate: 7558 kb/s
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Chapter #0:0: start 0.000000, end 730.187000
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: title : Main Titles/ The First Con
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Chapter #0:1: start 730.187000, end 1448.488000
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: title : Lonnegan Strikes Back
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Chapter #0:2: start 1448.488000, end 1739.738000
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: title : The Set-Up / The Great Henry Gondorff
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Chapter #0:3: start 1739.738000, end 2101.474000
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: title : The Round-Up
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Chapter #0:4: start 2101.474000, end 2430.594000
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: title : Playing for Lonnegan
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Chapter #0:5: start 2430.594000, end 2793.499000
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: title : The Hook
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Chapter #0:6: start 2793.499000, end 3659.530000
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: title : A Friendly Poker Game
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Chapter #0:7: start 3659.530000, end 4063.267000
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: title : The Double-Cross
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Chapter #0:8: start 4063.267000, end 4678.757000
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: title : The Tale
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Chapter #0:9: start 4678.757000, end 5022.892000
22:18:13.263 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: title : Hooker on the Lam
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Chapter #0:10: start 5022.892000, end 5436.222000
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: title : The Wire
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Chapter #0:11: start 5436.222000, end 5610.980000
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: title : A Federal Case
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Chapter #0:12: start 5610.980000, end 6092.377000
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: title : The Shut-Out
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Chapter #0:13: start 6092.377000, end 6740.024000
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: title : Hooker Hooked by the Feds
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Chapter #0:14: start 6740.024000, end 7153.521000
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: title : The Sting
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Chapter #0:15: start 7153.521000, end 7756.832000
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: title : The Big Con / End Credits
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Stream #0:0(und): Video: h264 (Main) (avc1 / 0x31637661), yuv420p(tv, bt709), 1920x1040 [SAR 1:1 DAR 24:13], 7388 kb/s, 23.98 fps, 23.98 tbr, 90k tbn, 180k tbc (default)
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: creation_time : 2017-03-26 08:19:45
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: handler_name : VideoHandler
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Stream #0:1(eng): Audio: aac (LC) (mp4a / 0x6134706D), 48000 Hz, stereo, fltp, 162 kb/s (default)
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: creation_time : 2017-03-26 08:19:45
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: handler_name : Stereo
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Stream #0:2(eng): Subtitle: mov_text (text / 0x74786574)
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: Metadata:
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: creation_time : 2017-03-26 08:19:45
22:18:13.264 T:140521292936960 INFO: ffmpeg[7FCDA9BFB700]: handler_name : SubtitleHandler
22:18:13.264 T:140521292936960 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0
22:18:13.264 T:140521292936960 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1
22:18:13.264 T:140521292936960 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 2
22:18:13.264 T:140521292936960 NOTICE: Opening stream: 0 source: 256
22:18:13.264 T:140521292936960 NOTICE: Creating video codec with codec id: 28
22:18:13.264 T:140521292936960 DEBUG: FactoryCodec - Video: - Opening
22:18:13.264 T:140521292936960 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
22:18:13.264 T:140521292936960 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
22:18:13.264 T:140521292936960 DEBUG: FactoryCodec - Video: ff-h264 - Opened
22:18:13.264 T:140521292936960 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28
22:18:13.264 T:140521292936960 NOTICE: Creating video thread
22:18:13.264 T:140521292936960 NOTICE: Opening stream: 1 source: 256
22:18:13.264 T:140521292936960 NOTICE: Finding audio codec for: 86018
22:18:13.264 T:140521292936960 DEBUG: FactoryCodec - Audio: passthrough - Opening
22:18:13.264 T:140521292936960 DEBUG: FactoryCodec - Audio: passthrough - Failed
22:18:13.265 T:140521292936960 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
22:18:13.265 T:140520793831168 DEBUG: Thread VideoPlayerVideo start, auto delete: false
22:18:13.265 T:140520793831168 NOTICE: running thread: video_thread
22:18:13.265 T:140521292936960 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
22:18:13.265 T:140521292936960 NOTICE: Creating audio thread
22:18:13.265 T:140521292936960 NOTICE: Opening stream: 2 source: 256
22:18:13.265 T:140520785438464 DEBUG: Thread VideoPlayerAudio start, auto delete: false
22:18:13.265 T:140521292936960 DEBUG: FactoryCodec - Overlay: TX3G Subtitle Decoder - Opening
22:18:13.265 T:140520785438464 NOTICE: running thread: CVideoPlayerAudio::Process()
22:18:13.266 T:140521292936960 DEBUG: FactoryCodec - Overlay: TX3G Subtitle Decoder - Opened
22:18:13.266 T:140521292936960 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://SERVER/movies/The Sting - 1973.m4v
22:18:13.266 T:140521292936960 DEBUG: Process - Start position set to last stopped position: 1100760
22:18:13.266 T:140521292936960 DEBUG: SeekTime - unknown position after seek
22:18:13.266 T:140521292936960 DEBUG: Process - starting demuxer from: 1100760
22:18:13.266 T:140521292936960 DEBUG: OnPlayBackStarted: play state was 1, starting 1
22:18:13.266 T:140521292936960 DEBUG: CVideoPlayer::SetCaching - caching state 2
22:18:13.266 T:140521292936960 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
22:18:13.266 T:140523296773376 DEBUG: PlayFile: OpenFile succeed, play state 2
22:18:13.266 T:140523296773376 DEBUG: OnPlayBackStarted: play state was 2, starting 0
22:18:13.272 T:140520793831168 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
22:18:13.276 T:140520785438464 DEBUG: CDVDAudio::Pause - pausing audio stream
22:18:13.276 T:140520785438464 NOTICE: Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through)
22:18:13.276 T:140520785438464 DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback
22:18:13.283 T:140520777045760 DEBUG: Thread Vaapi-Output start, auto delete: false
22:18:13.283 T:140520777045760 NOTICE: COutput::OnStartup: Output Thread created
22:18:13.286 T:140520777045760 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libsse4-x86_64-linux.so)
22:18:13.286 T:140520777045760 DEBUG: Loading: /usr/lib/kodi/system/libsse4-x86_64-linux.so
22:18:13.293 T:140520793831168 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vaapi
22:18:13.294 T:140522001790720 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://SERVER/movies/The Sting - 1973.m4v)
22:18:13.294 T:140523296757504 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
22:18:13.294 T:140523296757504 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
22:18:13.296 T:140523198080768 INFO: CActiveAESink::OpenSink - initialize sink
22:18:13.298 T:140522001790720 DEBUG: GetMovieId (smb://SERVER/movies/The Sting - 1973.m4v), query = select idMovie from movie where idFile=3932
22:18:13.307 T:140520793831168 DEBUG: CRenderManager::Configure - change configuration. 1920x1040. display: 1920x1040. framerate: 23.98. format: VAAPI
22:18:13.319 T:140523296773376 DEBUG: DeleteRenderer - deleting renderer
22:18:13.319 T:140523296773376 DEBUG: LinuxRendererGL: Cleaning up GL resources
22:18:13.320 T:140523296773376 DEBUG: Previous line repeats 1 times.
22:18:13.320 T:140523296773376 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
22:18:13.320 T:140523296773376 DEBUG: GL: Requested render method: 0
22:18:13.321 T:140523296773376 DEBUG: GL: BaseYUV2RGBGLSLShader: defines:
#define XBMC_DITHER
#define XBMC_texture_rectangle 0
#define XBMC_texture_rectangle_hack 0
#define XBMC_STRETCH 0
#define XBMC_NV12_RRG
22:18:13.321 T:140523296773376 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
22:18:13.326 T:140523296773376 DEBUG: GL: Vertex Shader compilation log:
22:18:13.326 T:140523296773376 DEBUG: GL: Vertex Shader compiled successfully
22:18:13.328 T:140523296773376 DEBUG: GL: Pixel Shader compilation log:
22:18:13.328 T:140523296773376 DEBUG: GL: Fragment Shader compiled successfully
22:18:13.342 T:140523296773376 DEBUG: GL: Vertex Shader compilation log:
22:18:13.342 T:140523296773376 DEBUG: GL: Vertex Shader compiled successfully
22:18:13.342 T:140523296773376 DEBUG: GL: Pixel Shader compilation log:
22:18:13.342 T:140523296773376 DEBUG: GL: Fragment Shader compiled successfully
22:18:13.347 T:140523296773376 NOTICE: GL: NPOT texture support detected
22:18:13.347 T:140523296773376 NOTICE: GL: Using GL_ARB_pixel_buffer_object
22:18:13.347 T:140523296773376 NOTICE: Using GL_TEXTURE_2D
22:18:13.347 T:140523296773376 DEBUG: CRenderManager::Configure - 4
22:18:13.347 T:140523296773376 DEBUG: GL: Requested render method: 0
22:18:13.347 T:140523296773376 DEBUG: GL: BaseYUV2RGBGLSLShader: defines:
#define XBMC_DITHER
#define XBMC_texture_rectangle 0
#define XBMC_texture_rectangle_hack 0
#define XBMC_STRETCH 0
#define XBMC_NV12_RRG
22:18:13.347 T:140521292936960 DEBUG: CVideoPlayer::HandleMessages - player started 2
22:18:13.347 T:140523296773376 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
22:18:13.348 T:140523296773376 DEBUG: GL: Vertex Shader compilation log:
22:18:13.348 T:140523296773376 DEBUG: GL: Vertex Shader compiled successfully
22:18:13.349 T:140523296773376 DEBUG: GL: Pixel Shader compilation log:
22:18:13.349 T:140523296773376 DEBUG: GL: Fragment Shader compiled successfully
22:18:13.362 T:140523296773376 NOTICE: GL: NPOT texture support detected
22:18:13.362 T:140523296773376 NOTICE: GL: Using GL_ARB_pixel_buffer_object
22:18:13.371 T:140523296773376 DEBUG: Activating window ID: 12005
22:18:13.371 T:140523296773376 DEBUG: ------ Window Deinit (Home.xml) ------
22:18:13.372 T:140523296773376 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
22:18:13.372 T:140523296773376 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
22:18:13.489 T:140523198080768 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@
22:18:13.489 T:140523198080768 INFO: CAESinkALSA::Initialize - Attempting to open device "@"
22:18:13.500 T:140523198080768 INFO: CAESinkALSA::Initialize - Opened device "sysdefault"
22:18:13.501 T:140523198080768 DEBUG: CAESinkALSA::SelectALSAChannelMap - Selected ALSA map "FL FR"
22:18:13.501 T:140523198080768 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
22:18:13.501 T:140523198080768 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
22:18:13.501 T:140523198080768 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 1024, bufferSize 9600
22:18:13.502 T:140523198080768 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 1024, bufferSize 9216
22:18:13.502 T:140523198080768 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 192 ms
22:18:13.502 T:140523198080768 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
22:18:13.502 T:140523198080768 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
22:18:13.502 T:140523198080768 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)
22:18:13.502 T:140523198080768 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
22:18:13.502 T:140523198080768 DEBUG: Output Device : Default (HDA Intel PCH ALC283 Analog)
22:18:13.502 T:140523198080768 DEBUG: Sample Rate : 48000
22:18:13.502 T:140523198080768 DEBUG: Sample Format : AE_FMT_S32NE
22:18:13.502 T:140523198080768 DEBUG: Channel Count : 2
22:18:13.502 T:140523198080768 DEBUG: Channel Layout: FL,FR
22:18:13.502 T:140523198080768 DEBUG: Frames : 1024
22:18:13.502 T:140523198080768 DEBUG: Frame Size : 8
22:18:13.505 T:140523206473472 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
22:18:13.507 T:140521292936960 DEBUG: CVideoPlayer::HandleMessages - player started 1
22:18:13.507 T:140521292936960 DEBUG: CVideoPlayer::SetCaching - caching state 3
22:18:13.507 T:140521292936960 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
22:18:13.507 T:140521292936960 DEBUG: CVideoPlayer::SetCaching - caching state 0
22:18:13.507 T:140521292936960 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
22:18:13.507 T:140521292936960 DEBUG: VideoPlayer::Sync - Audio - pts: 1102848000.000000, cache: 316744.294407, totalcache: 592000.007629
22:18:13.507 T:140521292936960 DEBUG: VideoPlayer::Sync - Video - pts: 1102768000.000000, cache: 50000.000000, totalcache: 100000.000000
22:18:13.507 T:140520785438464 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(1102531255.705593)
22:18:13.507 T:140520793831168 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(1102531255.705593)
22:18:13.507 T:140520785438464 DEBUG: CDVDAudio::Resume - resume audio stream
22:18:13.507 T:140523206473472 DEBUG: ActiveAE - start sync of audio stream
22:18:13.597 T:140523296773376 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
22:18:13.627 T:140523206473472 DEBUG: ActiveAE::SyncStream - average error of -27.672538, start adjusting
22:18:13.627 T:140523206473472 DEBUG: ActiveAE::SyncStream - average error -6.672538 below threshold of 30.000000
22:18:13.832 T:140520793831168 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 41727
22:18:14.651 T:140520785438464 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-21157.981996, adjusted:-21157.981996
22:18:18.599 T:140520793831168 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.33, frameduration: 41708.333333
22:18:20.274 T:140523026364160 DEBUG: PushCecKeypress - received key e0 duration 1000
22:18:20.281 T:140523296773376 DEBUG: OnKey: guide (0xe0) pressed, action is Stop
22:18:20.281 T:140523296773376 NOTICE: CVideoPlayer::CloseFile()
22:18:20.281 T:140523296773376 NOTICE: VideoPlayer: waiting for threads to exit
22:18:20.290 T:140521292936960 NOTICE: CVideoPlayer::OnExit()
22:18:20.290 T:140521292936960 NOTICE: Closing stream player 1
22:18:20.290 T:140521292936960 NOTICE: Waiting for audio thread to exit
22:18:20.305 T:140520785438464 NOTICE: thread end: CVideoPlayerAudio::OnExit()
22:18:20.305 T:140521292936960 NOTICE: Closing audio device
22:18:20.305 T:140520785438464 DEBUG: Thread VideoPlayerAudio 140520785438464 terminating
22:18:20.326 T:140521292936960 DEBUG: CDVDAudio::Flush - flush audio stream
22:18:20.326 T:140523206473472 DEBUG: CActiveAE::DiscardStream - audio stream deleted
22:18:20.326 T:140523206473472 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
22:18:20.327 T:140521292936960 DEBUG: Previous line repeats 2 times.
22:18:20.327 T:140521292936960 NOTICE: Deleting audio codec
22:18:20.327 T:140523198080768 INFO: CActiveAESink::OpenSink - initialize sink
22:18:20.327 T:140521292936960 NOTICE: Closing stream player 2
22:18:20.327 T:140521292936960 NOTICE: waiting for video thread to exit
22:18:20.330 T:140520793831168 NOTICE: thread end: video_thread
22:18:20.330 T:140520793831168 DEBUG: Thread VideoPlayerVideo 140520793831168 terminating
22:18:20.333 T:140521292936960 NOTICE: deleting video codec
22:18:20.336 T:140521292936960 NOTICE: Closing stream player 3
22:18:20.338 T:140521292936960 DEBUG: CSMBFile::Close closing fd 10000
22:18:20.339 T:140521292936960 DEBUG: OnPlayBackStopped: play state was 2, starting 0
22:18:20.340 T:140521292936960 DEBUG: Thread VideoPlayer 140521292936960 terminating
22:18:20.340 T:140523296773376 NOTICE: VideoPlayer: finished waiting
22:18:20.340 T:140523296773376 DEBUG: DeleteRenderer - deleting renderer
22:18:20.340 T:140523296773376 NOTICE: VAAPI::Close
22:18:20.341 T:140520777045760 NOTICE: COutput::OnExit: Output Thread terminated
22:18:20.341 T:140520777045760 DEBUG: Thread Vaapi-Output 140520777045760 terminating
22:18:20.344 T:140523296773376 NOTICE: VAAPI::Close - closing decoder context
22:18:20.348 T:140523296773376 DEBUG: LinuxRendererGL: Cleaning up GL resources
22:18:20.348 T:140523296773376 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
22:18:20.348 T:140523296773376 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
22:18:20.351 T:140523296757504 DEBUG: GetMovieId (smb://SERVER/movies/The Sting - 1973.m4v), query = select idMovie from movie where idFile=3932
22:18:20.358 T:140523296757504 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
22:18:20.358 T:140523296757504 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
22:18:20.360 T:140523296773376 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
22:18:20.360 T:140523296773376 DEBUG: ------ Window Init (Home.xml) ------
22:18:20.361 T:140523296773376 DEBUG: CDirectoryProvider[videodb://inprogresstvshows]: refreshing..
22:18:20.362 T:140523296773376 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing..
22:18:20.362 T:140523296773376 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing..
22:18:20.362 T:140523296773376 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing..
22:18:20.362 T:140523296773376 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing..
22:18:20.379 T:140522530268928 DEBUG: RunQuery took 8 ms for 0 items query: SELECT * FROM tvshow_view WHERE watchedCount != 0 AND totalCount != watchedCount ORDER BY c00
22:18:20.436 T:140523296773376 NOTICE: CVideoPlayer::CloseFile()
22:18:20.436 T:140523296773376 NOTICE: VideoPlayer: waiting for threads to exit
22:18:20.436 T:140523296773376 NOTICE: VideoPlayer: finished waiting
22:18:20.436 T:140523296773376 NOTICE: CVideoPlayer::CloseFile()
22:18:20.436 T:140523296773376 NOTICE: VideoPlayer: waiting for threads to exit
22:18:20.436 T:140523296773376 NOTICE: VideoPlayer: finished waiting
22:18:20.437 T:140523296773376 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
22:18:20.517 T:140523198080768 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@
22:18:20.517 T:140523198080768 INFO: CAESinkALSA::Initialize - Attempting to open device "@"
22:18:20.521 T:140523198080768 INFO: CAESinkALSA::Initialize - Opened device "sysdefault"
22:18:20.521 T:140523198080768 DEBUG: CAESinkALSA::SelectALSAChannelMap - Selected ALSA map "FL FR"
22:18:20.521 T:140523198080768 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
22:18:20.521 T:140523198080768 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
22:18:20.521 T:140523198080768 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 1024, bufferSize 9600
22:18:20.522 T:140523198080768 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 1024, bufferSize 9216
22:18:20.522 T:140523198080768 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 192 ms
22:18:20.522 T:140523198080768 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
22:18:20.522 T:140523198080768 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
22:18:20.522 T:140523198080768 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)
22:18:20.522 T:140523198080768 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
22:18:20.522 T:140523198080768 DEBUG: Output Device : Default (HDA Intel PCH ALC283 Analog)
22:18:20.522 T:140523198080768 DEBUG: Sample Rate : 48000
22:18:20.522 T:140523198080768 DEBUG: Sample Format : AE_FMT_S32NE
22:18:20.522 T:140523198080768 DEBUG: Channel Count : 2
22:18:20.522 T:140523198080768 DEBUG: Channel Layout: FL,FR
22:18:20.522 T:140523198080768 DEBUG: Frames : 1024
22:18:20.522 T:140523198080768 DEBUG: Frame Size : 8
22:18:20.522 T:140523206473472 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
22:18:20.637 T:140523075532544 DEBUG: DoWork - Saving file state for video item smb://SERVER/movies/The Sting - 1973.m4v
22:18:20.659 T:140523296757504 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc
22:18:20.659 T:140523296757504 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate
22:18:20.673 T:140523296773376 DEBUG: CDirectoryProvider[special://skin/playlists/inprogress_movies.xsp]: refreshing..
22:18:20.673 T:140523296773376 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_movies.xsp]: refreshing..
22:18:20.673 T:140523296773376 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_movies.xsp]: refreshing..
22:18:20.674 T:140523296773376 DEBUG: CDirectoryProvider[special://skin/playlists/random_movies.xsp]: refreshing..
22:18:20.674 T:140523296773376 DEBUG: CDirectoryProvider[videodb://movies/genres/]: refreshing..
22:18:20.674 T:140523296773376 DEBUG: CDirectoryProvider[videodb://movies/sets/]: refreshing..
22:18:20.684 T:140522538661632 DEBUG: RunQuery took 1 ms for 3 items query: select * from movie_view WHERE (movie_view.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
22:18:20.701 T:140523075532544 DEBUG: RunQuery took 18 ms for 923 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount = 0))
22:18:20.711 T:140522530268928 DEBUG: RunQuery took 27 ms for 923 items query: select * from movie_view WHERE ((movie_view.dateAdded > '1900-01-01')) AND ((movie_view.playCount IS NULL OR movie_view.playCount < 1))
22:18:20.726 T:140522538661632 DEBUG: RunQuery took 29 ms for 973 items query: select * from movie_view
22:18:20.742 T:140523075532544 DEBUG: RunQuery took 17 ms for 23 items query: SELECT genre.genre_id, genre.name, count(1), count(files.playCount) FROM genre JOIN genre_link ON genre.genre_id = genre_link.genre_id JOIN movie_view ON genre_link.media_id = movie_view.idMovie AND genre_link.media_type='movie' JOIN files ON files.idFile = movie_view.idFile GROUP BY genre.genre_id
22:18:20.779 T:140522538661632 DEBUG: RunQuery took 13 ms for 356 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet ORDER BY sets.idSet
22:18:34.708 T:140523296773376 DEBUG: LogindUPowerSyscall: Received PrepareForSleep with arg 1
22:18:34.708 T:140523296773376 DEBUG: ------ Window Init (DialogBusy.xml) ------
22:18:34.708 T:140523296757504 DEBUG: CAnnouncementManager - Announcement: OnSleep from xbmc
22:18:34.708 T:140523296773376 NOTICE: OnSleep: Running sleep jobs
22:18:34.708 T:140523296757504 DEBUG: GOT ANNOUNCEMENT, type: 8, from xbmc, message OnSleep
22:18:34.708 T:140523296773376 NOTICE: OnSleep: Stopping lirc
22:18:34.709 T:140523296773376 INFO: LIRC SetEnabled: disabled
22:18:34.709 T:140523296773376 DEBUG: CApplication::CloseNetworkShares: Closing all network shares
22:18:34.712 T:140523296773376 DEBUG: LogindUPowerSyscall - delay lock released
22:18:34.781 T:140523296773376 INFO: LIRC SetEnabled: disabled
22:20:22.460 T:140521305523968 DEBUG: Thread CECAdapterUpdate 140521305523968 terminating
22:20:22.461 T:140523017971456 DEBUG: Process - sending standby commands
22:20:22.464 T:140523206473472 ERROR: ActiveAE::UnconfigureSink - failed to unconfigure
22:20:22.547 T:140521708177152 NOTICE: ES: Client from ::ffff:127.0.0.1 timed out
22:20:22.562 T:140523288364800 DEBUG: CALSAHControlMonitor - Monitored ALSA hctl value changed
22:20:22.589 T:140523296773376 DEBUG: Previous line repeats 1 times.
22:20:22.589 T:140523296773376 DEBUG: NotifyXRREvent - notify display reset event
22:20:22.619 T:140523296773376 INFO: Available videomodes (xrandr):
22:20:22.619 T:140523296773376 INFO: Output 'HDMI1' has 22 modes
22:20:22.619 T:140523296773376 INFO: ID:0x48 Name:1920x1080 Refresh:60.000000 Width:1920 Height:1080
22:20:22.619 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:20:22.619 T:140523296773376 INFO: ID:0x102 Name:1920x1080 Refresh:50.000000 Width:1920 Height:1080
22:20:22.619 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:20:22.619 T:140523296773376 INFO: ID:0x103 Name:1920x1080 Refresh:59.940201 Width:1920 Height:1080
22:20:22.619 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:20:22.619 T:140523296773376 INFO: ID:0x106 Name:1920x1080 Refresh:24.000000 Width:1920 Height:1080
22:20:22.619 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:20:22.619 T:140523296773376 INFO: ID:0x108 Name:1920x1080 Refresh:23.976080 Width:1920 Height:1080
22:20:22.619 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:20:22.619 T:140523296773376 INFO: ID:0x104 Name:1920x1080i Refresh:30.000000 Width:1920 Height:1080
22:20:22.619 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:20:22.619 T:140523296773376 INFO: ID:0x105 Name:1920x1080i Refresh:25.000000 Width:1920 Height:1080
22:20:22.619 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:20:22.619 T:140523296773376 INFO: ID:0x107 Name:1920x1080i Refresh:29.970100 Width:1920 Height:1080
22:20:22.619 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:20:22.619 T:140523296773376 INFO: ID:0x109 Name:1280x720 Refresh:60.000000 Width:1280 Height:720
22:20:22.619 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:20:22.619 T:140523296773376 INFO: ID:0x10a Name:1280x720 Refresh:50.000000 Width:1280 Height:720
22:20:22.619 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:20:22.619 T:140523296773376 INFO: ID:0x10b Name:1280x720 Refresh:59.940201 Width:1280 Height:720
22:20:22.619 T:140523296773376 INFO: Pixel Ratio: 1.000000
22:20:22.619 T:140523296773376 INFO: ID:0x10c Name:1440x576 Refresh:50.000000 Width:1440 Height:576
22:20:22.619 T:140523296773376 INFO: Pixel Ratio: 0.711111
22:20:22.619 T:140523296773376 INFO: ID:0x10d Name:1440x480 Refresh:60.000000 Width:1440 Height:480
22:20:22.619 T:140523296773376 INFO: Pixel Ratio: 0.592593
22:20:22.620 T:140523296773376 INFO: ID:0x10e Name:1440x480 Refresh:59.940060 Width:1440 Height:480
22:20:22.620 T:140523296773376 INFO: Pixel Ratio: 0.592593
22:20:22.620 T:140523296773376 INFO: ID:0x10f Name:720x576 Refresh:50.000000 Width:720 Height:576
22:20:22.620 T:140523296773376 INFO: Pixel Ratio: 1.422222
22:20:22.620 T:140523296773376 INFO: ID:0x110 Name:720x576i Refresh:25.000000 Width:720 Height:576
22:20:22.620 T:140523296773376 INFO: Pixel Ratio: 1.422222
22:20:22.620 T:140523296773376 INFO: ID:0x111 Name:720x480 Refresh:60.000000 Width:720 Height:480
22:20:22.620 T:140523296773376 INFO: Pixel Ratio: 1.185185
22:20:22.620 T:140523296773376 INFO: ID:0x112 Name:720x480 Refresh:59.940060 Width:720 Height:480
22:20:22.620 T:140523296773376 INFO: Pixel Ratio: 1.185185
22:20:22.620 T:140523296773376 INFO: ID:0x113 Name:720x480i Refresh:30.001110 Width:720 Height:480
22:20:22.620 T:140523296773376 INFO: Pixel Ratio: 1.185185
22:20:22.620 T:140523296773376 INFO: ID:0x114 Name:720x480i Refresh:29.970030 Width:720 Height:480
22:20:22.620 T:140523296773376 INFO: Pixel Ratio: 1.185185
22:20:22.620 T:140523296773376 INFO: ID:0x115 Name:640x480 Refresh:60.000000 Width:640 Height:480
22:20:22.620 T:140523296773376 INFO: Pixel Ratio: 1.333333
22:20:22.620 T:140523296773376 INFO: ID:0x116 Name:640x480 Refresh:59.940479 Width:640 Height:480
22:20:22.620 T:140523296773376 INFO: Pixel Ratio: 1.333333
22:20:22.620 T:140523296773376 DEBUG: RecreateWindow - current output: HDMI1, mode: 0x48, refresh: 60.000
22:20:22.620 T:140523296773376 DEBUG: OnLostDevice - notify display change event
22:20:22.621 T:140523296773376 DEBUG: Window Manager Name: Fluxbox
22:20:22.627 T:140523296773376 DEBUG: CWinSystemX11::RefreshEGLContext: refreshing context
22:20:22.627 T:140523296773376 INFO: GL: Maximum texture width: 8192
22:20:22.628 T:140523206473472 DEBUG: CActiveAE - display reset event
22:20:22.628 T:140523198080768 INFO: CActiveAESink::OpenSink - initialize sink
22:20:22.628 T:140523198080768 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@
22:20:22.628 T:140523198080768 INFO: CAESinkALSA::Initialize - Attempting to open device "@"
22:20:22.628 T:140523296773376 ERROR: Keymapping error: no such action 'lirc.start' defined
22:20:22.632 T:140523198080768 INFO: CAESinkALSA::Initialize - Opened device "sysdefault"
22:20:22.633 T:140523198080768 DEBUG: CAESinkALSA::SelectALSAChannelMap - Selected ALSA map "FL FR"
22:20:22.633 T:140523198080768 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
22:20:22.633 T:140523198080768 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
22:20:22.633 T:140523198080768 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 1024, bufferSize 9600
22:20:22.633 T:140523198080768 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 1024, bufferSize 9216
22:20:22.633 T:140523198080768 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 192 ms
22:20:22.633 T:140523198080768 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
22:20:22.633 T:140523198080768 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
22:20:22.633 T:140523198080768 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)
22:20:22.633 T:140523198080768 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
22:20:22.633 T:140523198080768 DEBUG: Output Device : Default (HDA Intel PCH ALC283 Analog)
22:20:22.633 T:140523198080768 DEBUG: Sample Rate : 48000
22:20:22.633 T:140523198080768 DEBUG: Sample Format : AE_FMT_S32NE
22:20:22.633 T:140523198080768 DEBUG: Channel Count : 2
22:20:22.633 T:140523198080768 DEBUG: Channel Layout: FL,FR
22:20:22.633 T:140523198080768 DEBUG: Frames : 1024
22:20:22.633 T:140523198080768 DEBUG: Frame Size : 8
22:20:22.634 T:140523206473472 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
22:20:22.635 T:140523296773376 INFO: GL: Enabling VSYNC
22:20:22.994 T:140523296773376 DEBUG: LogindUPowerSyscall: Received PrepareForSleep with arg 0
22:20:22.994 T:140523296773376 NOTICE: OnWake: Running resume jobs
22:20:22.994 T:140523296773376 NOTICE: OnWake: Restarting lirc
22:20:22.994 T:140523206473472 WARNING: CActiveAE::StateMachine - signal: 0 from port: OutputControlPort not handled for state: 6
22:20:22.994 T:140521305523968 DEBUG: Thread RemoteControl start, auto delete: false
22:20:22.994 T:140521305523968 INFO: LIRC Process: using: /run/lirc/lircd
22:20:22.994 T:140521305523968 INFO: LIRC Connect: successfully started
22:20:22.994 T:140521305523968 DEBUG: Thread RemoteControl 140521305523968 terminating
22:20:23.559 T:140523017971456 DEBUG: Process - CEC adapter processor thread ended
22:20:23.559 T:140523017971456 DEBUG: Thread CECAdapter 140523017971456 terminating
22:20:28.763 T:140523296773376 ERROR: ActiveAE::Resume - failed to init
22:20:28.763 T:140523296757504 DEBUG: CAnnouncementManager - Announcement: OnWake from xbmc
22:20:28.763 T:140523296757504 DEBUG: GOT ANNOUNCEMENT, type: 8, from xbmc, message OnWake
22:20:28.763 T:140523296757504 DEBUG: Announce - reconnecting to the CEC adapter after standby mode
22:20:28.766 T:140523296773376 DEBUG: LogindUPowerSyscall - inhibit lock taken, fd 67
22:20:28.793 T:140523296773376 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
22:20:29.242 T:140523296757504 DEBUG: InitialiseFeature - using libCEC v4.0.1
22:20:29.242 T:140523017971456 DEBUG: Thread CECAdapter start, auto delete: false
22:20:29.242 T:140523017971456 DEBUG: OpenConnection - opening a connection to the CEC adapter: /dev/ttyACM0
22:20:29.279 T:140523296773376 DEBUG: ------ Window Init (DialogNotification.xml) ------
22:20:29.789 T:140523296773376 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/libsse4-x86_64-linux.so)
22:20:32.085 T:140523017971456 DEBUG: OpenConnection - connection to the CEC adapter opened
22:20:32.085 T:140523017971456 DEBUG: SetConfigurationFromLibCEC - settings updated by libCEC
22:20:32.085 T:140521990252288 DEBUG: Thread CECAdapterUpdate start, auto delete: false
22:20:34.253 T:140521990252288 DEBUG: UpdateMenuLanguage - using TV menu language is disabled
22:20:35.051 T:140523296773376 DEBUG: ------ Window Deinit (DialogNotification.xml) ------
22:20:35.172 T:140521990252288 DEBUG: UpdateAudioSystemStatus - no CEC capable amplifier found
22:20:35.188 T:140523296773376 DEBUG: ------ Window Init (DialogNotification.xml) ------
22:20:37.874 T:140523026364160 DEBUG: PushCecKeypress - received key a8 duration 0
22:20:37.886 T:140523296773376 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
22:20:38.356 T:140523026364160 DEBUG: PushCecKeypress - received key a8 duration 483
22:20:38.924 T:140523026364160 DEBUG: PushCecKeypress - received key a9 duration 0
22:20:38.935 T:140523296773376 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left
22:20:39.202 T:140523026364160 DEBUG: PushCecKeypress - received key a9 duration 278
22:20:39.723 T:140523026364160 DEBUG: PushCecKeypress - received key b duration 0
22:20:39.736 T:140523296773376 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
22:20:39.750 T:140523296773376 DEBUG: ------ Window Init (DialogContextMenu.xml) ------
22:20:40.038 T:140523026364160 DEBUG: PushCecKeypress - received key b duration 314
22:20:40.875 T:140523026364160 DEBUG: PushCecKeypress - received key b duration 0
22:20:40.886 T:140523296773376 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
22:20:40.947 T:140523296773376 DEBUG: ------ Window Deinit (DialogNotification.xml) ------
22:20:41.149 T:140523296773376 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------
22:20:41.157 T:140523296773376 DEBUG: Loading settings for smb://SERVER/movies/The Sting - 1973.m4v
22:20:41.164 T:140523296773376 DEBUG: CPlayerCoreFactory::GetPlayers(smb://SERVER/movies/The Sting - 1973.m4v)
22:20:41.164 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
22:20:41.164 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
22:20:41.164 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
22:20:41.164 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
22:20:41.164 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
22:20:41.164 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
22:20:41.164 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
22:20:41.164 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
22:20:41.164 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
22:20:41.164 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
22:20:41.164 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
22:20:41.164 T:140523296773376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
22:20:41.164 T:140523296773376 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
22:20:41.164 T:140523296773376 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
22:20:41.164 T:140523296773376 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
22:20:41.164 T:140523296773376 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
22:20:41.164 T:140523296773376 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
22:20:41.167 T:140523296773376 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
22:20:41.167 T:140523296773376 NOTICE: VideoPlayer: Opening: smb://SERVER/movies/The Sting - 1973.m4v
22:20:41.167 T:140523296773376 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
22:20:41.167 T:140523296773376 DEBUG: LinuxRendererGL: Cleaning up GL resources
22:20:41.167 T:140523296773376 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
22:20:41.167 T:140521305523968 DEBUG: Thread VideoPlayer start, auto delete: false
22:20:41.167 T:140521305523968 NOTICE: Creating InputStream
22:20:41.414 T:140521305523968 DEBUG: CSMBFile::Open - opened smb://SERVER/movies/The Sting - 1973.m4v, fd=10000
22:20:41.415 T:140521305523968 DEBUG: ScanForExternalSubtitles: Searching for subtitles...
22:20:41.515 T:140521305523968 DEBUG: ScanForExternalSubtitles: END (total time: 99 ms)
22:20:41.515 T:140521305523968 NOTICE: Creating Demuxer
22:20:41.517 T:140521305523968 DEBUG: Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
22:20:41.667 T:140523296773376 DEBUG: ------ Window Init (DialogBusy.xml) ------
22:20:41.855 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: [mov,mp4,m4a,3gp,3g2,mj2] Protocol name not provided, cannot determine if input is local or a network protocol, buffers and access patterns cannot be configured optimally without knowing the protocol
22:20:41.929 T:140521305523968 DEBUG: Open - avformat_find_stream_info starting
22:20:41.939 T:140521305523968 DEBUG: Open - av_find_stream_info finished
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Input #0, mov,mp4,m4a,3gp,3g2,mj2, smb://SERVER/movies/The Sting - 1973.m4':
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: major_brand : mp42
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: minor_version : 512
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: compatible_brands: isomiso2avc1mp41
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: creation_time : 2017-03-26 08:19:45
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: title : The Sting 1973 1080p Blu-ray Remux AVC DTS-HD MA 5.1 - KRaLiMaRKo
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: encoder : HandBrake 0.10.2 2016021200
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Duration: 02:09:16.83, start: 0.000000, bitrate: 7558 kb/s
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Chapter #0:0: start 0.000000, end 730.187000
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: title : Main Titles/ The First Con
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Chapter #0:1: start 730.187000, end 1448.488000
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: title : Lonnegan Strikes Back
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Chapter #0:2: start 1448.488000, end 1739.738000
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: title : The Set-Up / The Great Henry Gondorff
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Chapter #0:3: start 1739.738000, end 2101.474000
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: title : The Round-Up
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Chapter #0:4: start 2101.474000, end 2430.594000
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: title : Playing for Lonnegan
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Chapter #0:5: start 2430.594000, end 2793.499000
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: title : The Hook
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Chapter #0:6: start 2793.499000, end 3659.530000
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: title : A Friendly Poker Game
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Chapter #0:7: start 3659.530000, end 4063.267000
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: title : The Double-Cross
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Chapter #0:8: start 4063.267000, end 4678.757000
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: title : The Tale
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Chapter #0:9: start 4678.757000, end 5022.892000
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: title : Hooker on the Lam
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Chapter #0:10: start 5022.892000, end 5436.222000
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: title : The Wire
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Chapter #0:11: start 5436.222000, end 5610.980000
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: title : A Federal Case
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Chapter #0:12: start 5610.980000, end 6092.377000
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: title : The Shut-Out
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Chapter #0:13: start 6092.377000, end 6740.024000
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: title : Hooker Hooked by the Feds
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Chapter #0:14: start 6740.024000, end 7153.521000
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: title : The Sting
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Chapter #0:15: start 7153.521000, end 7756.832000
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: title : The Big Con / End Credits
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Stream #0:0(und): Video: h264 (Main) (avc1 / 0x31637661), yuv420p(tv, bt709), 1920x1040 [SAR 1:1 DAR 24:13], 7388 kb/s, 23.98 fps, 23.98 tbr, 90k tbn, 180k tbc (default)
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: creation_time : 2017-03-26 08:19:45
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: handler_name : VideoHandler
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Stream #0:1(eng): Audio: aac (LC) (mp4a / 0x6134706D), 48000 Hz, stereo, fltp, 162 kb/s (default)
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: creation_time : 2017-03-26 08:19:45
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: handler_name : Stereo
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Stream #0:2(eng): Subtitle: mov_text (text / 0x74786574)
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: Metadata:
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: creation_time : 2017-03-26 08:19:45
22:20:41.939 T:140521305523968 INFO: ffmpeg[7FCDAA7FC700]: handler_name : SubtitleHandler
22:20:41.939 T:140521305523968 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0
22:20:41.939 T:140521305523968 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1
22:20:41.939 T:140521305523968 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 2
22:20:41.939 T:140521305523968 NOTICE: Opening stream: 0 source: 256
22:20:41.939 T:140521305523968 NOTICE: Creating video codec with codec id: 28
22:20:41.939 T:140521305523968 DEBUG: FactoryCodec - Video: - Opening
22:20:41.939 T:140521305523968 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
22:20:41.939 T:140521305523968 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
22:20:41.939 T:140521305523968 DEBUG: FactoryCodec - Video: ff-h264 - Opened
22:20:41.939 T:140521305523968 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28
22:20:41.939 T:140521305523968 NOTICE: Creating video thread
22:20:41.939 T:140521305523968 NOTICE: Opening stream: 1 source: 256
22:20:41.939 T:140521305523968 NOTICE: Finding audio codec for: 86018
22:20:41.939 T:140521305523968 DEBUG: FactoryCodec - Audio: passthrough - Opening
22:20:41.939 T:140521305523968 DEBUG: FactoryCodec - Audio: passthrough - Failed
22:20:41.939 T:140521305523968 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
22:20:41.939 T:140520780637952 DEBUG: Thread VideoPlayerVideo start, auto delete: false
22:20:41.939 T:140520780637952 NOTICE: running thread: video_thread
22:20:41.940 T:140521305523968 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
22:20:41.940 T:140521305523968 NOTICE: Creating audio thread
22:20:41.940 T:140521305523968 NOTICE: Opening stream: 2 source: 256
22:20:41.940 T:140521305523968 DEBUG: FactoryCodec - Overlay: TX3G Subtitle Decoder - Opening
22:20:41.940 T:140521305523968 DEBUG: FactoryCodec - Overlay: TX3G Subtitle Decoder - Opened
22:20:41.940 T:140520738068224 DEBUG: Thread VideoPlayerAudio start, auto delete: false
22:20:41.940 T:140520738068224 NOTICE: running thread: CVideoPlayerAudio::Process()
22:20:41.940 T:140521305523968 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://SERVER/movies/The Sting - 1973.m4v
22:20:41.940 T:140521305523968 DEBUG: Process - Start position set to last stopped position: 1109134
22:20:41.940 T:140521305523968 DEBUG: SeekTime - unknown position after seek
22:20:41.940 T:140521305523968 DEBUG: Process - starting demuxer from: 1109134
22:20:41.940 T:140521305523968 DEBUG: OnPlayBackStarted: play state was 1, starting 1
22:20:41.940 T:140521305523968 DEBUG: CVideoPlayer::SetCaching - caching state 2
22:20:41.940 T:140521305523968 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
22:20:41.944 T:140520780637952 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
22:20:41.945 T:140520659613440 DEBUG: Thread Vaapi-Output start, auto delete: false
22:20:41.945 T:140520659613440 NOTICE: COutput::OnStartup: Output Thread created
22:20:41.948 T:140520659613440 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libsse4-x86_64-linux.so)
22:20:41.948 T:140520659613440 DEBUG: Loading: /usr/lib/kodi/system/libsse4-x86_64-linux.so
22:20:41.950 T:140520738068224 DEBUG: CDVDAudio::Pause - pausing audio stream
22:20:41.950 T:140520738068224 NOTICE: Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through)
22:20:41.951 T:140520738068224 DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback
22:20:41.955 T:140520780637952 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vaapi
22:20:41.956 T:140523296773376 DEBUG: PlayFile: OpenFile succeed, play state 2
22:20:41.956 T:140523296773376 DEBUG: OnPlayBackStarted: play state was 2, starting 0
22:20:41.965 T:140523198080768 INFO: CActiveAESink::OpenSink - initialize sink
22:20:41.972 T:140520780637952 DEBUG: CRenderManager::Configure - change configuration. 1920x1040. display: 1920x1040. framerate: 23.98. format: VAAPI
22:20:41.973 T:140523296757504 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
22:20:41.973 T:140522530268928 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://SERVER/movies/The Sting - 1973.m4v)
22:20:41.973 T:140523296757504 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
22:20:41.975 T:140523296773376 DEBUG: DeleteRenderer - deleting renderer
22:20:41.975 T:140523296773376 DEBUG: LinuxRendererGL: Cleaning up GL resources
22:20:41.975 T:140523296773376 DEBUG: Previous line repeats 1 times.
22:20:41.975 T:140523296773376 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
22:20:41.975 T:140523296773376 DEBUG: GL: Requested render method: 0
22:20:41.975 T:140523296773376 DEBUG: GL: BaseYUV2RGBGLSLShader: defines:
#define XBMC_DITHER
#define XBMC_texture_rectangle 0
#define XBMC_texture_rectangle_hack 0
#define XBMC_STRETCH 0
#define XBMC_NV12_RRG
22:20:41.975 T:140523296773376 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
22:20:41.976 T:140523296773376 DEBUG: GL: Vertex Shader compilation log:
22:20:41.976 T:140523296773376 DEBUG: GL: Vertex Shader compiled successfully
22:20:41.976 T:140522530268928 DEBUG: GetMovieId (smb://SERVER/movies/The Sting - 1973.m4v), query = select idMovie from movie where idFile=3932
22:20:41.977 T:140523296773376 DEBUG: GL: Pixel Shader compilation log:
22:20:41.977 T:140523296773376 DEBUG: GL: Fragment Shader compiled successfully
22:20:41.991 T:140523296773376 DEBUG: GL: Vertex Shader compilation log:
22:20:41.991 T:140523296773376 DEBUG: GL: Vertex Shader compiled successfully
22:20:41.991 T:140523296773376 DEBUG: GL: Pixel Shader compilation log:
22:20:41.991 T:140523296773376 DEBUG: GL: Fragment Shader compiled successfully
22:20:41.995 T:140523296773376 NOTICE: GL: NPOT texture support detected
22:20:41.995 T:140523296773376 NOTICE: GL: Using GL_ARB_pixel_buffer_object
22:20:41.995 T:140523296773376 NOTICE: Using GL_TEXTURE_2D
22:20:41.995 T:140523296773376 DEBUG: CRenderManager::Configure - 4
22:20:41.995 T:140523296773376 DEBUG: GL: Requested render method: 0
22:20:41.995 T:140523296773376 DEBUG: GL: BaseYUV2RGBGLSLShader: defines:
#define XBMC_DITHER
#define XBMC_texture_rectangle 0
#define XBMC_texture_rectangle_hack 0
#define XBMC_STRETCH 0
#define XBMC_NV12_RRG
22:20:41.995 T:140523296773376 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
22:20:41.996 T:140521305523968 DEBUG: CVideoPlayer::HandleMessages - player started 2
22:20:41.996 T:140523296773376 DEBUG: GL: Vertex Shader compilation log:
22:20:41.996 T:140523296773376 DEBUG: GL: Vertex Shader compiled successfully
22:20:41.998 T:140523296773376 DEBUG: GL: Pixel Shader compilation log:
22:20:41.998 T:140523296773376 DEBUG: GL: Fragment Shader compiled successfully
22:20:42.009 T:140523296773376 NOTICE: GL: NPOT texture support detected
22:20:42.009 T:140523296773376 NOTICE: GL: Using GL_ARB_pixel_buffer_object
22:20:42.013 T:140523296773376 DEBUG: Activating window ID: 12005
22:20:42.013 T:140523296773376 DEBUG: ------ Window Deinit (Home.xml) ------
22:20:42.013 T:140523296773376 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
22:20:42.157 T:140523198080768 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@
22:20:42.157 T:140523198080768 INFO: CAESinkALSA::Initialize - Attempting to open device "@"
22:20:42.167 T:140523198080768 INFO: CAESinkALSA::Initialize - Opened device "sysdefault"
22:20:42.168 T:140523198080768 DEBUG: CAESinkALSA::SelectALSAChannelMap - Selected ALSA map "FL FR"
22:20:42.168 T:140523198080768 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
22:20:42.168 T:140523198080768 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
22:20:42.168 T:140523198080768 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 1024, bufferSize 9600
22:20:42.169 T:140523198080768 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 1024, bufferSize 9216
22:20:42.169 T:140523198080768 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 192 ms
22:20:42.169 T:140523198080768 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
22:20:42.169 T:140523198080768 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
22:20:42.169 T:140523198080768 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)
22:20:42.169 T:140523198080768 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
22:20:42.169 T:140523198080768 DEBUG: Output Device : Default (HDA Intel PCH ALC283 Analog)
22:20:42.169 T:140523198080768 DEBUG: Sample Rate : 48000
22:20:42.169 T:140523198080768 DEBUG: Sample Format : AE_FMT_S32NE
22:20:42.169 T:140523198080768 DEBUG: Channel Count : 2
22:20:42.169 T:140523198080768 DEBUG: Channel Layout: FL,FR
22:20:42.169 T:140523198080768 DEBUG: Frames : 1024
22:20:42.169 T:140523198080768 DEBUG: Frame Size : 8
22:20:42.172 T:140523206473472 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
22:20:42.177 T:140521305523968 DEBUG: CVideoPlayer::HandleMessages - player started 1
22:20:42.178 T:140521305523968 DEBUG: CVideoPlayer::SetCaching - caching state 3
22:20:42.178 T:140521305523968 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
22:20:42.178 T:140521305523968 DEBUG: CVideoPlayer::SetCaching - caching state 0
22:20:42.178 T:140521305523968 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
22:20:42.178 T:140521305523968 DEBUG: VideoPlayer::Sync - Audio - pts: 1112064000.000000, cache: 317209.622067, totalcache: 592000.007629
22:20:42.178 T:140521305523968 DEBUG: VideoPlayer::Sync - Video - pts: 1111944000.000000, cache: 50000.000000, totalcache: 100000.000000
22:20:42.178 T:140520738068224 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(1111746790.377933)
22:20:42.178 T:140520780637952 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(1111746790.377933)
22:20:42.178 T:140520738068224 DEBUG: CDVDAudio::Resume - resume audio stream
22:20:42.178 T:140523206473472 DEBUG: ActiveAE - start sync of audio stream
22:20:42.230 T:140523296773376 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
22:20:42.295 T:140523206473472 DEBUG: ActiveAE::SyncStream - average error of -66.256443, start adjusting
22:20:42.295 T:140523206473472 DEBUG: ActiveAE::SyncStream - average error -24.256443 below threshold of 30.000000
22:20:42.465 T:140520780637952 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 41727
22:20:43.319 T:140520738068224 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-38409.614537, adjusted:-38409.614537
22:20:47.248 T:140520780637952 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.33, frameduration: 41708.333333
22:21:11.973 T:140522001790720 DEBUG: Thread JobWorker 140522001790720 terminating (autodelete)
22:21:11.973 T:140523075532544 DEBUG: Thread JobWorker 140523075532544 terminating (autodelete)
22:21:11.974 T:140522538661632 DEBUG: Thread JobWorker 140522538661632 terminating (autodelete)
22:21:11.987 T:140522530268928 DEBUG: Thread JobWorker 140522530268928 terminating (autodelete)
22:21:23.371 T:140521708177152 NOTICE: ES: Client from ::ffff:127.0.0.1 timed out