Attachment 124321 Details for Bug 96382 – Kodi log

Kodi log

kodi_log.txt (text/plain), 196.19 KB, created by Chris on 2016-06-05 00:12:41 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Chris

Created: 2016-06-05 00:12:41 UTC

Size: 196.19 KB

patch

obsolete

>23:23:33   5.728594 T:139653791922240  NOTICE: special://profile/ is mapped to: special://masterprofile/
>23:23:33   5.728610 T:139653791922240  NOTICE: -----------------------------------------------------------------------
>23:23:33   5.728617 T:139653791922240  NOTICE: Starting Kodi (17.0-ALPHA2 Git:1647d30). Platform: Linux x86 64-bit
>23:23:33   5.728621 T:139653791922240  NOTICE: Using Release Kodi x64 build
>23:23:33   5.728626 T:139653791922240  NOTICE: Kodi compiled Jun  4 2016 by GCC 5.3.0 for Linux x86 64-bit version 4.6.1 (263681)
>23:23:33   5.728660 T:139653791922240  NOTICE: Running on LibreELEC (Milhouse) - Version: devel-20160604210510-#0604-g6bd3b8c [Build #0604] 8.0, kernel: Linux x86 64-bit version 4.6.1
>23:23:33   5.728665 T:139653791922240  NOTICE: FFmpeg version: 3.0.1
>23:23:33   5.728669 T:139653791922240  NOTICE: WARNING: unsupported ffmpeg version detected
>23:23:33   5.728673 T:139653791922240  NOTICE: Host CPU: Intel(R) Core(TM) i7-6770HQ CPU @ 2.60GHz, 8 cores available
>23:23:33   5.728677 T:139653791922240  NOTICE: special://xbmc/ is mapped to: /usr/share/kodi
>23:23:33   5.728681 T:139653791922240  NOTICE: special://xbmcbin/ is mapped to: /usr/lib/kodi
>23:23:33   5.728685 T:139653791922240  NOTICE: special://masterprofile/ is mapped to: /storage/.kodi/userdata
>23:23:33   5.728688 T:139653791922240  NOTICE: special://home/ is mapped to: /storage/.kodi
>23:23:33   5.728692 T:139653791922240  NOTICE: special://temp/ is mapped to: /storage/.kodi/temp
>23:23:33   5.728695 T:139653791922240  NOTICE: special://logpath/ is mapped to: /storage/.kodi/temp
>23:23:33   5.728703 T:139653791922240  NOTICE: The executable running is: /usr/lib/kodi/kodi.bin
>23:23:33   5.728713 T:139653791922240  NOTICE: Local hostname: NUCTest
>23:23:33   5.728723 T:139653791922240  NOTICE: Log File is located: /storage/.kodi/temp//kodi.log
>23:23:33   5.730710 T:139653791922240  NOTICE: -----------------------------------------------------------------------
>23:23:33   5.753080 T:139653791922240   ERROR: DBus: Error org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files
>23:23:33   5.755802 T:139653791922240  NOTICE: load settings...
>23:23:33   5.787799 T:139653791922240  NOTICE: Found 2 Lists of Devices
>23:23:33   5.787808 T:139653791922240  NOTICE: Enumerated ALSA devices:
>23:23:33   5.787811 T:139653791922240  NOTICE:     Device 1
>23:23:33   5.787865 T:139653791922240  NOTICE:         m_deviceName      : @
>23:23:33   5.787869 T:139653791922240  NOTICE:         m_displayName     : Default (HDA Intel PCH ALC233 Analog)
>23:23:33   5.787872 T:139653791922240  NOTICE:         m_displayNameExtra:
>23:23:33   5.787875 T:139653791922240  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
>23:23:33   5.787878 T:139653791922240  NOTICE:         m_channels        : FL,FR
>23:23:33   5.787880 T:139653791922240  NOTICE:         m_sampleRates     : 48000
>23:23:33   5.787883 T:139653791922240  NOTICE:         m_dataFormats     : AE_FMT_S32NE
>23:23:33   5.787886 T:139653791922240  NOTICE:         m_streamTypes     : No passthrough capabilities
>23:23:33   5.787892 T:139653791922240  NOTICE:     Device 2
>23:23:33   5.787898 T:139653791922240  NOTICE:         m_deviceName      : @:CARD=PCH,DEV=0
>23:23:33   5.787900 T:139653791922240  NOTICE:         m_displayName     : HDA Intel PCH
>23:23:33   5.787903 T:139653791922240  NOTICE:         m_displayNameExtra: ALC233 Analog
>23:23:33   5.787905 T:139653791922240  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
>23:23:33   5.787908 T:139653791922240  NOTICE:         m_channels        : FL,FR
>23:23:33   5.787910 T:139653791922240  NOTICE:         m_sampleRates     : 48000
>23:23:33   5.787912 T:139653791922240  NOTICE:         m_dataFormats     : AE_FMT_S32NE
>23:23:33   5.787915 T:139653791922240  NOTICE:         m_streamTypes     : No passthrough capabilities
>23:23:33   5.787917 T:139653791922240  NOTICE:     Device 3
>23:23:33   5.787923 T:139653791922240  NOTICE:         m_deviceName      : iec958:CARD=PCH,DEV=0
>23:23:33   5.787926 T:139653791922240  NOTICE:         m_displayName     : HDA Intel PCH
>23:23:33   5.787931 T:139653791922240  NOTICE:         m_displayNameExtra: ALC233 Digital S/PDIF
>23:23:33   5.787934 T:139653791922240  NOTICE:         m_deviceType      : AE_DEVTYPE_IEC958
>23:23:33   5.787937 T:139653791922240  NOTICE:         m_channels        : FL,FR
>23:23:33   5.787939 T:139653791922240  NOTICE:         m_sampleRates     : 44100,48000,88200,96000,192000
>23:23:33   5.787941 T:139653791922240  NOTICE:         m_dataFormats     : AE_FMT_RAW,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE
>23:23:33   5.787945 T:139653791922240  NOTICE:         m_streamTypes     : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512
>23:23:33   5.787948 T:139653791922240  NOTICE:     Device 4
>23:23:33   5.787955 T:139653791922240  NOTICE:         m_deviceName      : hdmi:CARD=PCH,DEV=0
>23:23:33   5.787958 T:139653791922240  NOTICE:         m_displayName     : HDA Intel PCH
>23:23:33   5.787961 T:139653791922240  NOTICE:         m_displayNameExtra: MJI marantz-AVR on DisplayPort #0
>23:23:33   5.787963 T:139653791922240  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI
>23:23:33   5.787966 T:139653791922240  NOTICE:         m_channels        : FL,FR,LFE,FC,BL,BR,BC,BLOC,BROC
>23:23:33   5.787969 T:139653791922240  NOTICE:         m_sampleRates     : 32000,44100,48000,88200,96000,176400,192000
>23:23:33   5.787971 T:139653791922240  NOTICE:         m_dataFormats     : AE_FMT_RAW,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW
>23:23:33   5.787975 T:139653791922240  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
>23:23:33   5.787978 T:139653791922240  NOTICE:     Device 5
>23:23:33   5.787985 T:139653791922240  NOTICE:         m_deviceName      : hdmi:CARD=PCH,DEV=1
>23:23:33   5.787988 T:139653791922240  NOTICE:         m_displayName     : HDA Intel PCH
>23:23:33   5.787990 T:139653791922240  NOTICE:         m_displayNameExtra: HDMI #1
>23:23:33   5.787992 T:139653791922240  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI
>23:23:33   5.787995 T:139653791922240  NOTICE:         m_channels        : FL,FR,LFE,FC,BC,BL,BR,FLOC,FROC,BLOC,BROC,TFC,TC,TFL,TFR,UNKNOWN1
>23:23:33   5.787998 T:139653791922240  NOTICE:         m_sampleRates     : 32000,44100,48000,88200,96000,176400,192000
>23:23:33   5.788001 T:139653791922240  NOTICE:         m_dataFormats     : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW
>23:23:33   5.788003 T:139653791922240  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
>23:23:33   5.788006 T:139653791922240  NOTICE:     Device 6
>23:23:33   5.788013 T:139653791922240  NOTICE:         m_deviceName      : hdmi:CARD=PCH,DEV=2
>23:23:33   5.788016 T:139653791922240  NOTICE:         m_displayName     : HDA Intel PCH
>23:23:33   5.788018 T:139653791922240  NOTICE:         m_displayNameExtra: HDMI #2
>23:23:33   5.788021 T:139653791922240  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI
>23:23:33   5.788023 T:139653791922240  NOTICE:         m_channels        : FL,FR,LFE,FC,BC,BL,BR,FLOC,FROC,BLOC,BROC,TFC,TC,TFL,TFR,UNKNOWN1
>23:23:33   5.788026 T:139653791922240  NOTICE:         m_sampleRates     : 32000,44100,48000,88200,96000,176400,192000
>23:23:33   5.788028 T:139653791922240  NOTICE:         m_dataFormats     : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW
>23:23:33   5.788031 T:139653791922240  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
>23:23:33   5.788034 T:139653791922240  NOTICE: Enumerated PULSE devices:
>23:23:33   5.788036 T:139653791922240  NOTICE:     Device 1
>23:23:33   5.788042 T:139653791922240  NOTICE:         m_deviceName      : Default
>23:23:33   5.788044 T:139653791922240  NOTICE:         m_displayName     : Default
>23:23:33   5.788049 T:139653791922240  NOTICE:         m_displayNameExtra: Bluetooth Audio (PULSEAUDIO)
>23:23:33   5.788052 T:139653791922240  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
>23:23:33   5.788054 T:139653791922240  NOTICE:         m_channels        : FL,FR
>23:23:33   5.788057 T:139653791922240  NOTICE:         m_sampleRates     : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
>23:23:33   5.788060 T:139653791922240  NOTICE:         m_dataFormats     : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT
>23:23:33   5.788063 T:139653791922240  NOTICE:         m_streamTypes     : No passthrough capabilities
>23:23:33   5.791797 T:139653791922240  NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml
>23:23:33   5.793497 T:139653791922240  NOTICE: Contents of special://xbmc/system/advancedsettings.xml are...
>                                            <?xml version="1.0" encoding="UTF-8" ?>
>                                            <advancedsettings>
>                                              <showexitbutton>false</showexitbutton>
>                                              <cputempcommand>cputemp</cputempcommand>
>                                              <gputempcommand>gputemp</gputempcommand>
>                                              <video>
>                                                <latency>
>                                                  <delay>0</delay>
>                                                  <refresh>
>                                                    <min>23</min>
>                                                    <max>24</max>
>                                                    <delay>175</delay>
>                                                  </refresh>
>                                                </latency>
>                                                <busydialogdelayms>750</busydialogdelayms>
>                                              </video>
>                                              <samba>
>                                                <clienttimeout>30</clienttimeout>
>                                              </samba>
>                                            </advancedsettings>
>23:23:33   5.793674 T:139653791922240  NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
>23:23:33   5.793679 T:139653791922240  NOTICE: Default Video Player: VideoPlayer
>23:23:33   5.793683 T:139653791922240  NOTICE: Default Audio Player: paplayer
>23:23:33   5.793686 T:139653791922240  NOTICE: Enabled debug logging due to GUI setting (2)
>23:23:33   5.793689 T:139653791922240  NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM"
>23:23:33   5.793711 T:139653791922240  NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
>23:23:33   5.793917 T:139653791922240  NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
>23:23:33   5.794428 T:139653791922240   DEBUG: CPlayerCoreConfig::<ctor>: created player VideoPlayer
>23:23:33   5.794436 T:139653791922240   DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer
>23:23:33   5.794439 T:139653791922240   DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
>23:23:33   5.794443 T:139653791922240   DEBUG: CPlayerSelectionRule::Initialize: creating rule: mms/udp
>23:23:33   5.794447 T:139653791922240   DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
>23:23:33   5.794451 T:139653791922240   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
>23:23:33   5.794455 T:139653791922240   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
>23:23:33   5.794459 T:139653791922240   DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
>23:23:33   5.794462 T:139653791922240   DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
>23:23:33   5.794466 T:139653791922240   DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
>23:23:33   5.794469 T:139653791922240   DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
>23:23:33   5.794476 T:139653791922240   DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
>23:23:33   5.794479 T:139653791922240   DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
>23:23:33   5.794482 T:139653791922240   DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
>23:23:33   5.794486 T:139653791922240   DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
>23:23:33   5.794489 T:139653791922240  NOTICE: Loaded playercorefactory configuration
>23:23:33   5.794522 T:139653791922240  NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
>23:23:33   5.794540 T:139653791922240  NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
>23:23:33   5.795894 T:139653791922240    INFO: creating subdirectories
>23:23:33   5.795902 T:139653791922240    INFO: userdata folder: special://masterprofile/
>23:23:33   5.795907 T:139653791922240    INFO: recording folder:
>23:23:33   5.795912 T:139653791922240    INFO: screenshots folder: /storage/screenshots/
>23:23:33   5.807949 T:139653791922240  NOTICE: Running database version Addons24
>23:23:33   5.808043 T:139653791922240   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-x86_64-linux.so)
>23:23:33   5.808060 T:139653791922240   DEBUG: Loading: /usr/lib/kodi/system/libcpluff-x86_64-linux.so
>23:23:33   5.838762 T:139653791922240    INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/lib/kodi/addons: No such file or directory'
>23:23:33   5.838783 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.'
>23:23:33   5.838797 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been installed.'
>23:23:33   5.838803 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in resource.images.weathericons.default has been installed.'
>23:23:33   5.838807 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in xbmc.audioencoder has been installed.'
>23:23:33   5.838811 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.vbox has been installed.'
>23:23:33   5.838816 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.'
>23:23:33   5.838820 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.'
>23:23:33   5.838824 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in kodi.audiodecoder has been installed.'
>23:23:33   5.838829 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.'
>23:23:33   5.838833 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.vdr.vnsi has been installed.'
>23:23:33   5.838837 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.argustv has been installed.'
>23:23:33   5.838841 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in kodi.peripheral has been installed.'
>23:23:33   5.838845 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in kodi.guilib has been installed.'
>23:23:33   5.838849 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.mediaportal.tvserver has been installed.'
>23:23:33   5.838853 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in peripheral.joystick has been installed.'
>23:23:33   5.838857 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in kodi.adsp has been installed.'
>23:23:33   5.838862 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.wma has been installed.'
>23:23:33   5.838872 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in game.controller.default has been installed.'
>23:23:33   5.838877 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in service.libreelec.settings has been installed.'
>23:23:33   5.838881 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.'
>23:23:33   5.838885 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in kodi.game has been installed.'
>23:23:33   5.838890 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.'
>23:23:33   5.838896 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.'
>23:23:33   5.838903 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.wmc has been installed.'
>23:23:33   5.838908 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.dvblink has been installed.'
>23:23:33   5.838912 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.hdhomerun has been installed.'
>23:23:33   5.838916 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in resource.language.nb_no has been installed.'
>23:23:33   5.838922 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in kodi.resource has been installed.'
>23:23:33   5.838925 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.'
>23:23:33   5.838930 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.'
>23:23:33   5.838933 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in os.openelec.tv has been installed.'
>23:23:33   5.838937 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in repository.kodi.game has been installed.'
>23:23:33   5.838941 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.vuplus has been installed.'
>23:23:33   5.838945 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.mythtv has been installed.'
>23:23:33   5.838948 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.'
>23:23:33   5.838953 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in inputstream.mpd has been installed.'
>23:23:33   5.838957 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.youtube has been installed.'
>23:23:33   5.838961 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.demo has been installed.'
>23:23:33   5.838964 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in os.libreelec.tv has been installed.'
>23:23:33   5.838968 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.pctv has been installed.'
>23:23:33   5.838972 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in inputstream.smoothstream has been installed.'
>23:23:33   5.838976 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in kodi.inputstream has been installed.'
>23:23:33   5.838980 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in repository.libreelec.tv has been installed.'
>23:23:33   5.838984 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.iptvsimple has been installed.'
>23:23:33   5.838987 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been installed.'
>23:23:33   5.838991 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.njoy has been installed.'
>23:23:33   5.838995 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.nextpvr has been installed.'
>23:23:33   5.838999 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.aac has been installed.'
>23:23:33   5.839003 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been installed.'
>23:23:33   5.839008 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.'
>23:23:33   5.839012 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in resource.language.en_gb has been installed.'
>23:23:33   5.839015 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.dvbviewer has been installed.'
>23:23:33   5.839020 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.stalker has been installed.'
>23:23:33   5.839024 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.'
>23:23:33   5.839029 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.'
>23:23:33   5.839033 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been installed.'
>23:23:33   5.839039 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been installed.'
>23:23:33   5.839043 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in xbmc.webinterface has been installed.'
>23:23:33   5.839048 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.confluence has been installed.'
>23:23:33   5.839053 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.'
>23:23:33   5.839059 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been installed.'
>23:23:33   5.839064 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been installed.'
>23:23:33   5.839070 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.'
>23:23:33   5.839074 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.'
>23:23:33   5.839078 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.filmon has been installed.'
>23:23:33   5.839082 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.'
>23:23:33   5.839086 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in pvr.hts has been installed.'
>23:23:33   5.839089 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been installed.'
>23:23:33   5.839093 T:139653791922240   DEBUG: ADDON: cpluff: 'Plug-in skin.estuary has been installed.'
>23:23:33   5.839096 T:139653791922240   DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
>23:23:33   5.852360 T:139653791922240  NOTICE: ADDONS: Using repository repository.kodi.game
>23:23:33   5.852381 T:139653791922240  NOTICE: ADDONS: Using repository repository.libreelec.tv
>23:23:33   5.852388 T:139653791922240  NOTICE: ADDONS: Using repository repository.xbmc.org
>23:23:33   5.853600 T:139653791922240   DEBUG: ADDON: Dll Initializing - InputStream MPEG DASH
>23:23:33   5.853875 T:139653791922240   DEBUG: SECTION:LoadDLL(/usr/lib/kodi/addons/inputstream.mpd/inputstream.mpd.so.1.1.0)
>23:23:33   5.853896 T:139653791922240   DEBUG: Loading: /usr/lib/kodi/addons/inputstream.mpd/inputstream.mpd.so.1.1.0
>23:23:33   5.868781 T:139653791922240   DEBUG: AddOnLog: InputStream MPEG DASH: libXBMC_addon successfully loaded
>23:23:33   5.868860 T:139653791922240   DEBUG: AddOnLog: InputStream MPEG DASH: ADDON_Create()
>23:23:33   5.868867 T:139653791922240   DEBUG: AddOnLog: InputStream MPEG DASH: ADDON_Destroy()
>23:23:33   5.868937 T:139653791922240   DEBUG: SECTION:UnloadDll(/usr/lib/kodi/addons/inputstream.mpd/inputstream.mpd.so.1.1.0)
>23:23:33   5.868986 T:139653791922240    INFO: ADDON: Dll Destroyed - InputStream MPEG DASH
>23:23:33   5.869027 T:139653791922240   DEBUG: ADDON: Dll Initializing - InputStream SmoothStreamingMedia
>23:23:33   5.869062 T:139653791922240   DEBUG: SECTION:LoadDLL(/usr/lib/kodi/addons/inputstream.smoothstream/inputstream.smoothstream.so.1.1.0)
>23:23:33   5.869074 T:139653791922240   DEBUG: Loading: /usr/lib/kodi/addons/inputstream.smoothstream/inputstream.smoothstream.so.1.1.0
>23:23:33   5.878259 T:139653791922240   DEBUG: AddOnLog: InputStream SmoothStreamingMedia: ADDON_Create()
>23:23:33   5.878282 T:139653791922240   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'InputStream SmoothStreamingMedia' requests setting 'URL1'
>23:23:33   5.879070 T:139653791922240   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'InputStream SmoothStreamingMedia' requests setting 'URL2'
>23:23:33   5.879152 T:139653791922240   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'InputStream SmoothStreamingMedia' requests setting 'URL3'
>23:23:33   5.879228 T:139653791922240   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'InputStream SmoothStreamingMedia' requests setting 'URL4'
>23:23:33   5.879303 T:139653791922240   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'InputStream SmoothStreamingMedia' requests setting 'URL5'
>23:23:33   5.879376 T:139653791922240   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'InputStream SmoothStreamingMedia' requests setting 'URL6'
>23:23:33   5.879450 T:139653791922240   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'InputStream SmoothStreamingMedia' requests setting 'URL7'
>23:23:33   5.879523 T:139653791922240   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'InputStream SmoothStreamingMedia' requests setting 'URL8'
>23:23:33   5.879597 T:139653791922240   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'InputStream SmoothStreamingMedia' requests setting 'URL9'
>23:23:33   5.879674 T:139653791922240   ERROR: CAddonCallbacksAddon - GetAddonSetting - can't find setting 'URL9' in 'InputStream SmoothStreamingMedia'
>23:23:33   5.879680 T:139653791922240   DEBUG: AddOnLog: InputStream SmoothStreamingMedia: ADDON_Destroy()
>23:23:33   5.879733 T:139653791922240   DEBUG: SECTION:UnloadDll(/usr/lib/kodi/addons/inputstream.smoothstream/inputstream.smoothstream.so.1.1.0)
>23:23:33   5.879770 T:139653791922240    INFO: ADDON: Dll Destroyed - InputStream SmoothStreamingMedia
>23:23:33   5.879885 T:139653772986112   DEBUG: Thread ActiveAE start, auto delete: false
>23:23:33   5.880003 T:139653763618560   DEBUG: Thread AESink start, auto delete: false
>23:23:33   5.880294 T:139653763618560    INFO: CActiveAESink::OpenSink - initialize sink
>23:23:33   5.880311 T:139653763618560   DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0
>23:23:33   5.880317 T:139653763618560    INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0"
>23:23:33   5.881709 T:139653763618560    INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"
>23:23:33   5.881901 T:139653763618560    INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
>23:23:33   5.881912 T:139653763618560    INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
>23:23:33   5.881921 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820
>23:23:33   5.890508 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820
>23:23:33   5.890538 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
>23:23:33   5.891649 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
>23:23:33   5.891663 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
>23:23:33   5.891671 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)
>23:23:33   5.891681 T:139653763618560   DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
>23:23:33   5.891689 T:139653763618560   DEBUG:   Output Device : HDA Intel PCH
>23:23:33   5.891696 T:139653763618560   DEBUG:   Sample Rate   : 44100
>23:23:33   5.891703 T:139653763618560   DEBUG:   Sample Format : AE_FMT_S32NE
>23:23:33   5.891710 T:139653763618560   DEBUG:   Channel Count : 2
>23:23:33   5.891717 T:139653763618560   DEBUG:   Channel Layout: FL,FR
>23:23:33   5.891725 T:139653763618560   DEBUG:   Frames        : 2205
>23:23:33   5.891731 T:139653763618560   DEBUG:   Frame Size    : 8
>23:23:33   5.896626 T:139653545981696   DEBUG: Thread RemoteControl start, auto delete: false
>23:23:33   5.896664 T:139653545981696    INFO: LIRC Process: using: /run/lirc/lircd
>23:23:33   5.896773 T:139653545981696    INFO: LIRC Connect: successfully started
>23:23:33   5.896790 T:139653545981696   DEBUG: Thread RemoteControl 139653545981696 terminating
>23:23:33   5.899041 T:139653791922240    INFO: CKeyboardLayoutManager: loading keyboard layouts from special://xbmc/system/keyboardlayouts...
>23:23:33   5.903007 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Arabic QWERTY" successfully loaded
>23:23:33   5.903480 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian Ð¯ÐÐÐ Ð¢Ðª" successfully loaded
>23:23:33   5.903646 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian ÐÐÐ" successfully loaded
>23:23:33   5.904080 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BasePY" successfully loaded
>23:23:33   5.904245 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BaiduPY" successfully loaded
>23:23:33   5.904619 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Czech QWERTZ" successfully loaded
>23:23:33   5.904960 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Danish QWERTY" successfully loaded
>23:23:33   5.905427 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "English QWERTY" successfully loaded
>23:23:33   5.905590 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "English AZERTY" successfully loaded
>23:23:33   5.905739 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "English ABC" successfully loaded
>23:23:33   5.906106 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "French AZERTY" successfully loaded
>23:23:33   5.906440 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "German QWERTZ" successfully loaded
>23:23:33   5.906782 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Greek QWERTY" successfully loaded
>23:23:33   5.907166 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew QWERTY" successfully loaded
>23:23:33   5.907278 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew ABC" successfully loaded
>23:23:33   5.907542 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Hungarian QWERTZ" successfully loaded
>23:23:33   5.907797 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Korean ã±ã´ã·" successfully loaded
>23:23:33   5.908109 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian AZERTY" successfully loaded
>23:23:33   5.908238 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian QWERTY" successfully loaded
>23:23:33   5.908492 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Norwegian QWERTY" successfully loaded
>23:23:33   5.908749 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Polish QWERTY" successfully loaded
>23:23:33   5.909019 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Portuguese (Portugal) QWERTY" successfully loaded
>23:23:33   5.909275 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Romanian QWERTY" successfully loaded
>23:23:33   5.909586 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Russian ÐÐ¦Ð£ÐÐÐ" successfully loaded
>23:23:33   5.909711 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Russian ÐÐÐ" successfully loaded
>23:23:33   5.909984 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Spanish QWERTY" successfully loaded
>23:23:33   5.910242 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Swedish QWERTY" successfully loaded
>23:23:33   5.910488 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Turkish QWERTY" successfully loaded
>23:23:33   5.910794 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian ÐÐ¦Ð£ÐÐÐ" successfully loaded
>23:23:33   5.910918 T:139653791922240   DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian ÐÐÐ" successfully loaded
>23:23:33   5.911371 T:139653791922240   DEBUG: UDisks: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UDisks was not provided by any .service files
>23:23:33   5.911609 T:139653791922240   DEBUG: DeviceKit.Disks: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.DeviceKit.Disks was not provided by any .service files
>23:23:33   5.912459 T:139653791922240   DEBUG: Selected UDev as storage provider
>23:23:34   6.179642 T:139653791922240    INFO: Available videomodes (xrandr):
>23:23:34   6.179677 T:139653791922240    INFO: Output 'DP-1' has 38 modes
>23:23:34   6.179692 T:139653791922240    INFO: ID:0x49 Name:3840x2160 Refresh:60.000000 Width:3840 Height:2160
>23:23:34   6.179727 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.179757 T:139653791922240    INFO: ID:0x4c Name:3840x2160 Refresh:30.000000 Width:3840 Height:2160
>23:23:34   6.179769 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.179783 T:139653791922240    INFO: ID:0x4d Name:3840x2160 Refresh:25.000000 Width:3840 Height:2160
>23:23:34   6.179794 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.179808 T:139653791922240    INFO: ID:0x4e Name:3840x2160 Refresh:24.000000 Width:3840 Height:2160
>23:23:34   6.179834 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.179850 T:139653791922240    INFO: ID:0x4f Name:3840x2160 Refresh:29.969999 Width:3840 Height:2160
>23:23:34   6.179861 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.179877 T:139653791922240    INFO: ID:0x50 Name:3840x2160 Refresh:23.976000 Width:3840 Height:2160
>23:23:34   6.179887 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.179901 T:139653791922240    INFO: ID:0x4a Name:4096x2160 Refresh:24.000000 Width:4096 Height:2160
>23:23:34   6.179912 T:139653791922240    INFO: Pixel Ratio: 0.937500
>23:23:34   6.179927 T:139653791922240    INFO: ID:0x4b Name:4096x2160 Refresh:23.976000 Width:4096 Height:2160
>23:23:34   6.179937 T:139653791922240    INFO: Pixel Ratio: 0.937500
>23:23:34   6.179951 T:139653791922240    INFO: ID:0x51 Name:1920x1080 Refresh:60.000000 Width:1920 Height:1080
>23:23:34   6.179961 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.179974 T:139653791922240    INFO: ID:0x52 Name:1920x1080 Refresh:50.000000 Width:1920 Height:1080
>23:23:34   6.179984 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.179998 T:139653791922240    INFO: ID:0x53 Name:1920x1080 Refresh:59.940201 Width:1920 Height:1080
>23:23:34   6.180034 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.180048 T:139653791922240    INFO: ID:0x56 Name:1920x1080 Refresh:30.000000 Width:1920 Height:1080
>23:23:34   6.180058 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.180071 T:139653791922240    INFO: ID:0x57 Name:1920x1080 Refresh:25.000000 Width:1920 Height:1080
>23:23:34   6.180081 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.180094 T:139653791922240    INFO: ID:0x58 Name:1920x1080 Refresh:24.000000 Width:1920 Height:1080
>23:23:34   6.180104 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.180118 T:139653791922240    INFO: ID:0x5a Name:1920x1080 Refresh:29.970100 Width:1920 Height:1080
>23:23:34   6.180128 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.180142 T:139653791922240    INFO: ID:0x5b Name:1920x1080 Refresh:23.976080 Width:1920 Height:1080
>23:23:34   6.180152 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.180166 T:139653791922240    INFO: ID:0x54 Name:1920x1080i Refresh:30.000000 Width:1920 Height:1080
>23:23:34   6.180176 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.180190 T:139653791922240    INFO: ID:0x55 Name:1920x1080i Refresh:25.000000 Width:1920 Height:1080
>23:23:34   6.180199 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.180222 T:139653791922240    INFO: ID:0x59 Name:1920x1080i Refresh:29.970100 Width:1920 Height:1080
>23:23:34   6.180233 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.180246 T:139653791922240    INFO: ID:0x5c Name:2880x576 Refresh:50.000000 Width:2880 Height:576
>23:23:34   6.180257 T:139653791922240    INFO: Pixel Ratio: 0.355556
>23:23:34   6.180270 T:139653791922240    INFO: ID:0x5d Name:2880x480 Refresh:60.000000 Width:2880 Height:480
>23:23:34   6.180280 T:139653791922240    INFO: Pixel Ratio: 0.296296
>23:23:34   6.180295 T:139653791922240    INFO: ID:0x5e Name:2880x480 Refresh:59.940060 Width:2880 Height:480
>23:23:34   6.180305 T:139653791922240    INFO: Pixel Ratio: 0.296296
>23:23:34   6.180319 T:139653791922240    INFO: ID:0x5f Name:1280x1024 Refresh:60.019741 Width:1280 Height:1024
>23:23:34   6.180329 T:139653791922240    INFO: Pixel Ratio: 1.422222
>23:23:34   6.180344 T:139653791922240    INFO: ID:0x60 Name:1152x864 Refresh:59.966919 Width:1152 Height:864
>23:23:34   6.180353 T:139653791922240    INFO: Pixel Ratio: 1.333333
>23:23:34   6.180367 T:139653791922240    INFO: ID:0x61 Name:1280x720 Refresh:60.000000 Width:1280 Height:720
>23:23:34   6.180376 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.180389 T:139653791922240    INFO: ID:0x62 Name:1280x720 Refresh:50.000000 Width:1280 Height:720
>23:23:34   6.180398 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.180412 T:139653791922240    INFO: ID:0x63 Name:1280x720 Refresh:59.940201 Width:1280 Height:720
>23:23:34   6.180430 T:139653791922240    INFO: Pixel Ratio: 1.000000
>23:23:34   6.180444 T:139653791922240    INFO: ID:0x64 Name:1440x576 Refresh:50.000000 Width:1440 Height:576
>23:23:34   6.180454 T:139653791922240    INFO: Pixel Ratio: 0.711111
>23:23:34   6.180468 T:139653791922240    INFO: ID:0x65 Name:1024x768 Refresh:60.003841 Width:1024 Height:768
>23:23:34   6.180478 T:139653791922240    INFO: Pixel Ratio: 1.333333
>23:23:34   6.180491 T:139653791922240    INFO: ID:0x66 Name:1440x480 Refresh:60.000000 Width:1440 Height:480
>23:23:34   6.180501 T:139653791922240    INFO: Pixel Ratio: 0.592593
>23:23:34   6.180515 T:139653791922240    INFO: ID:0x67 Name:1440x480 Refresh:59.940060 Width:1440 Height:480
>23:23:34   6.180525 T:139653791922240    INFO: Pixel Ratio: 0.592593
>23:23:34   6.180539 T:139653791922240    INFO: ID:0x68 Name:800x600 Refresh:60.316540 Width:800 Height:600
>23:23:34   6.180549 T:139653791922240    INFO: Pixel Ratio: 1.333333
>23:23:34   6.180562 T:139653791922240    INFO: ID:0x69 Name:720x576 Refresh:50.000000 Width:720 Height:576
>23:23:34   6.180573 T:139653791922240    INFO: Pixel Ratio: 1.422222
>23:23:34   6.180585 T:139653791922240    INFO: ID:0x6a Name:720x480 Refresh:60.000000 Width:720 Height:480
>23:23:34   6.180595 T:139653791922240    INFO: Pixel Ratio: 1.185185
>23:23:34   6.180608 T:139653791922240    INFO: ID:0x6b Name:720x480 Refresh:59.940060 Width:720 Height:480
>23:23:34   6.180618 T:139653791922240    INFO: Pixel Ratio: 1.185185
>23:23:34   6.180631 T:139653791922240    INFO: ID:0x6c Name:640x480 Refresh:60.000000 Width:640 Height:480
>23:23:34   6.180641 T:139653791922240    INFO: Pixel Ratio: 1.333333
>23:23:34   6.180655 T:139653791922240    INFO: ID:0x6d Name:640x480 Refresh:59.940479 Width:640 Height:480
>23:23:34   6.180665 T:139653791922240    INFO: Pixel Ratio: 1.333333
>23:23:34   6.180679 T:139653791922240    INFO: ID:0x6e Name:720x400 Refresh:70.081657 Width:720 Height:400
>23:23:34   6.180688 T:139653791922240    INFO: Pixel Ratio: 0.987654
>23:23:34   6.180719 T:139653791922240  NOTICE: Checking resolution 16
>23:23:34   6.203001 T:139653791922240   DEBUG: Window Manager Name: Fluxbox
>23:23:34   6.241208 T:139653791922240  NOTICE: Using visual 0x21
>23:23:34   6.245623 T:139653791922240    INFO: GL: Maximum texture width: 8192
>23:23:34   6.245743 T:139653791922240   DEBUG: EGL_EXTENSIONS: EGL_CHROMIUM_sync_control EGL_EXT_buffer_age 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_cubemap_image EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap 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_texture_from_pixmap
>23:23:34   6.245753 T:139653791922240  NOTICE: GL_VENDOR = Intel Open Source Technology Center
>23:23:34   6.245776 T:139653791922240  NOTICE: GL_RENDERER = Mesa DRI Intel(R) Iris Pro Graphics 580 (Skylake GT4e)
>23:23:34   6.245779 T:139653791922240  NOTICE: GL_VERSION = 3.0 Mesa 12.0.0-rc1 (git-94a45f3)
>23:23:34   6.245782 T:139653791922240  NOTICE: GL_SHADING_LANGUAGE_VERSION = 1.30
>23:23:34   6.245872 T:139653791922240  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_context_flush_control GL_KHR_robust_buffer_access_behavior GL_ARB_shader_clock GL_EXT_shader_samples_identical
>23:23:34   6.246057 T:139653791922240    INFO: GL: Maximum texture width: 8192
>23:23:34   6.290217 T:139653791922240    INFO: GL: Enabling VSYNC
>23:23:34   6.290242 T:139653791922240   ERROR: GL: Vertical Blank Syncing unsupported
>23:23:34   6.301371 T:139653791922240    INFO: load keymapping
>23:23:34   6.302357 T:139653791922240    INFO: Loading special://xbmc/system/keymaps/appcommand.xml
>23:23:34   6.302523 T:139653791922240    INFO: Loading special://xbmc/system/keymaps/customcontroller.AppleRemote.xml
>23:23:34   6.303182 T:139653791922240    INFO: Loading special://xbmc/system/keymaps/customcontroller.Harmony.xml
>23:23:34   6.304513 T:139653791922240    INFO: Loading special://xbmc/system/keymaps/gamepad.xml
>23:23:34   6.305336 T:139653791922240    INFO: Loading special://xbmc/system/keymaps/joystick.xml
>23:23:34   6.306049 T:139653791922240    INFO: Loading special://xbmc/system/keymaps/keyboard.xml
>23:23:34   6.308319 T:139653791922240    INFO: Loading special://xbmc/system/keymaps/mouse.xml
>23:23:34   6.308510 T:139653791922240    INFO: Loading special://xbmc/system/keymaps/remote.xml
>23:23:34   6.310188 T:139653791922240    INFO: Loading special://xbmc/system/keymaps/touchscreen.xml
>23:23:34   6.310905 T:139653791922240    INFO: Loading special://xbmc/system/Lircmap.xml
>23:23:34   6.311551 T:139653791922240    INFO: * Adding remote mapping for device 'mceusb'
>23:23:34   6.311582 T:139653791922240    INFO: * Adding remote mapping for device 'XboxDVDDongle'
>23:23:34   6.311591 T:139653791922240    INFO: * Adding remote mapping for device 'Microsoft_Xbox'
>23:23:34   6.311599 T:139653791922240    INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
>23:23:34   6.311610 T:139653791922240    INFO: * Adding remote mapping for device 'anysee'
>23:23:34   6.311621 T:139653791922240    INFO: * Adding remote mapping for device 'iMON-PAD'
>23:23:34   6.311633 T:139653791922240    INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
>23:23:34   6.311646 T:139653791922240    INFO: * Adding remote mapping for device 'MCE_via_iMON'
>23:23:34   6.311663 T:139653791922240    INFO: * Adding remote mapping for device 'TwinHanRemote'
>23:23:34   6.311673 T:139653791922240    INFO: * Adding remote mapping for device 'linux-input-layer'
>23:23:34   6.311698 T:139653791922240    INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
>23:23:34   6.311702 T:139653791922240    INFO: * Adding remote mapping for device 'mediacenter'
>23:23:34   6.311716 T:139653791922240    INFO: * Adding remote mapping for device 'devinput'
>23:23:34   6.311842 T:139653791922240   DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
>23:23:34   6.311848 T:139653791922240    INFO: GUI format 3840x2160, Display 3840x2160@ 60.00 - Full Screen
>23:23:34   6.311851 T:139653791922240   DEBUG: guilib: Fill viewport on change for solving rendering passes
>23:23:34   6.311911 T:139653791922240    INFO: CLangInfo: loading resource.language.en_gb language information...
>23:23:34   6.312717 T:139653791922240   DEBUG: trying to set locale to en_DE.UTF-8
>23:23:34   6.319414 T:139653791922240    INFO: global locale set to C
>23:23:34   6.319452 T:139653791922240    INFO: CLangInfo: loading resource.language.en_gb language strings...
>23:23:34   6.326025 T:139653791922240   DEBUG: LocalizeStrings: loaded 3682 strings from file resource://resource.language.en_gb/strings.po
>23:23:34   6.329916 T:139653791922240   DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.xbmc.builtin.aac/resources/language/English/strings.po
>23:23:34   6.330031 T:139653791922240   DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.xbmc.builtin.wma/resources/language/English/strings.po
>23:23:34   6.330582 T:139653791922240   DEBUG: LocalizeStrings: loaded 18 strings from file /usr/share/kodi/addons/game.controller.default/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.330973 T:139653791922240   DEBUG: LocalizeStrings: loaded 13 strings from file /storage/.kodi/addons/metadata.album.universal/resources/language/English/strings.po
>23:23:34   6.331378 T:139653791922240   DEBUG: LocalizeStrings: loaded 26 strings from file /storage/.kodi/addons/metadata.artists.universal/resources/language/English/strings.po
>23:23:34   6.332396 T:139653791922240   DEBUG: LocalizeStrings: loaded 7 strings from file /storage/.kodi/addons/metadata.themoviedb.org/resources/language/English/strings.po
>23:23:34   6.332808 T:139653791922240   DEBUG: LocalizeStrings: loaded 7 strings from file /storage/.kodi/addons/metadata.tvdb.com/resources/language/English/strings.po
>23:23:34   6.333450 T:139653791922240   DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/peripheral.joystick/resources/language/English/strings.po
>23:23:34   6.334567 T:139653791922240   DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/screensaver.xbmc.builtin.dim/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.335525 T:139653791922240   DEBUG: LocalizeStrings: loaded 189 strings from file /usr/share/kodi/addons/service.libreelec.settings/resources/language/English/strings.po
>23:23:34   6.336349 T:139653791922240   DEBUG: LocalizeStrings: loaded 108 strings from file /storage/.kodi/addons/plugin.video.youtube/resources/language/English/strings.po
>23:23:34   6.337107 T:139653791922240   DEBUG: LocalizeStrings: loaded 5 strings from file /usr/share/kodi/addons/inputstream.mpd/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.337164 T:139653791922240   DEBUG: LocalizeStrings: loaded 6 strings from file /usr/share/kodi/addons/inputstream.smoothstream/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.337746 T:139653791922240   DEBUG: LocalizeStrings: loaded 8 strings from file /usr/share/kodi/addons/pvr.argustv/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.338469 T:139653791922240   DEBUG: LocalizeStrings: loaded 67 strings from file /usr/share/kodi/addons/pvr.dvblink/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.338977 T:139653791922240   DEBUG: LocalizeStrings: loaded 34 strings from file /usr/share/kodi/addons/pvr.dvbviewer/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.339342 T:139653791922240   DEBUG: LocalizeStrings: loaded 3 strings from file /usr/share/kodi/addons/pvr.filmon/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.339767 T:139653791922240   DEBUG: LocalizeStrings: loaded 5 strings from file /usr/share/kodi/addons/pvr.hdhomerun/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.340466 T:139653791922240   DEBUG: LocalizeStrings: loaded 68 strings from file /usr/share/kodi/addons/pvr.hts/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.340965 T:139653791922240   DEBUG: LocalizeStrings: loaded 22 strings from file /usr/share/kodi/addons/pvr.iptvsimple/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.341492 T:139653791922240   DEBUG: LocalizeStrings: loaded 71 strings from file /usr/share/kodi/addons/pvr.mediaportal.tvserver/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.342103 T:139653791922240   DEBUG: LocalizeStrings: loaded 93 strings from file /usr/share/kodi/addons/pvr.mythtv/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.342595 T:139653791922240   DEBUG: LocalizeStrings: loaded 28 strings from file /usr/share/kodi/addons/pvr.nextpvr/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.343004 T:139653791922240   DEBUG: LocalizeStrings: loaded 2 strings from file /usr/share/kodi/addons/pvr.njoy/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.343478 T:139653791922240   DEBUG: LocalizeStrings: loaded 8 strings from file /usr/share/kodi/addons/pvr.pctv/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.343930 T:139653791922240   DEBUG: LocalizeStrings: loaded 43 strings from file /usr/share/kodi/addons/pvr.stalker/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.344368 T:139653791922240   DEBUG: LocalizeStrings: loaded 16 strings from file /usr/share/kodi/addons/pvr.vbox/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.344961 T:139653791922240   DEBUG: LocalizeStrings: loaded 69 strings from file /usr/share/kodi/addons/pvr.vdr.vnsi/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.345531 T:139653791922240   DEBUG: LocalizeStrings: loaded 27 strings from file /usr/share/kodi/addons/pvr.vuplus/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.346082 T:139653791922240   DEBUG: LocalizeStrings: loaded 70 strings from file /usr/share/kodi/addons/pvr.wmc/resources/language/resource.language.en_gb/strings.po
>23:23:34   6.347008 T:139653791922240   DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
>23:23:34   6.347053 T:139653791922240   DEBUG: LoadMappings - loaded node "CEC Adapter"
>23:23:34   6.347062 T:139653791922240   DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
>23:23:34   6.347082 T:139653791922240   DEBUG: LoadMappings - loaded node "iMON HID device"
>23:23:34   6.347128 T:139653791922240   DEBUG: CPeripheralBusUSB - initialised udev monitor
>23:23:34   6.347136 T:139653791922240   DEBUG: SECTION:LoadDLL(libcec.so.3)
>23:23:34   6.347426 T:139653791922240   DEBUG: Loading: libcec.so.3
>23:23:34   6.352514 T:139653791922240   DEBUG: PERIPHERAL - CreateAddon - creating peripheral add-on instance 'Joystick Support'
>23:23:34   6.352523 T:139653791922240   DEBUG: ADDON: Dll Initializing - Joystick Support
>23:23:34   6.352633 T:139653791922240   DEBUG: SECTION:LoadDLL(/usr/lib/kodi/addons/peripheral.joystick/peripheral.joystick.so.1.1.0)
>23:23:34   6.352643 T:139653791922240   DEBUG: Loading: /usr/lib/kodi/addons/peripheral.joystick/peripheral.joystick.so.1.1.0
>23:23:34   6.359780 T:139653791922240   DEBUG: Calling TransferSettings for: Joystick Support
>23:23:34   6.360415 T:139653791922240   DEBUG: AddOnLog: Joystick Support: Setting "deadzone" set to 0.200000
>23:23:34   6.360491 T:139653507815168   DEBUG: Thread PeripBusUSBUdev start, auto delete: false
>23:23:34   6.360588 T:139653499422464   DEBUG: Thread PeripBusCEC start, auto delete: false
>23:23:34   6.360684 T:139653491029760   DEBUG: Thread PeripBusAddon start, auto delete: false
>23:23:34   6.360769 T:139653791922240   DEBUG: SECTION:LoadDLL(libcurl.so.4)
>23:23:34   6.360814 T:139653210437376   DEBUG: Thread PeripEventScanner start, auto delete: false
>23:23:34   6.361176 T:139653791922240   DEBUG: Loading: libcurl.so.4
>23:23:34   6.367466 T:139653791922240  NOTICE: Running database version Addons24
>23:23:34   6.367549 T:139653791922240   DEBUG: Initialize, updating databases...
>23:23:34   6.367919 T:139653791922240  NOTICE: Running database version ViewModes6
>23:23:34   6.368658 T:139653791922240  NOTICE: Running database version Textures13
>23:23:34   6.369718 T:139653791922240  NOTICE: Running database version MyMusic60
>23:23:34   6.371489 T:139653791922240  NOTICE: Running database version MyVideos105
>23:23:34   6.372059 T:139653791922240  NOTICE: Running database version TV29
>23:23:34   6.372464 T:139653791922240  NOTICE: Running database version Epg11
>23:23:34   6.372801 T:139653791922240   DEBUG: Initialize, updating databases... DONE
>23:23:34   6.372808 T:139653791922240  NOTICE: start dvd mediatype detection
>23:23:34   6.372923 T:139653482182400   DEBUG: Thread DetectDVDMedia start, auto delete: false
>23:23:34   6.372934 T:139653482182400   DEBUG: Compiled with libcdio Version 0.93
>23:23:34   6.372947 T:139653791922240   DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
>23:23:34   6.373158 T:139653482182400   DEBUG: Thread DetectDVDMedia 139653482182400 terminating
>23:23:34   6.378039 T:139653791913728   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
>23:23:34   6.378075 T:139653791913728   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
>23:23:34   6.378229 T:139653791922240   DEBUG: Activating window ID: 12997
>23:23:34   6.378240 T:139653791922240   DEBUG: ------ Window Init () ------
>23:23:34   6.378271 T:139653791922240    INFO: load splash image: /usr/share/kodi/media/Splash.png
>23:23:34   6.378784 T:139653791922240    INFO: Unloading old skin ...
>23:23:34   6.378839 T:139653791922240    INFO:   load skin from: /usr/share/kodi/addons/skin.estuary (version: 0.7.13)
>23:23:34   6.378985 T:139653791922240    INFO:   load fonts for skin...
>23:23:34   6.380832 T:139653791922240    INFO: Loading fonts from /usr/share/kodi/addons/skin.estuary/1080i/Font.xml
>23:23:34   6.398645 T:139653791922240   DEBUG: LocalizeStrings: loaded 135 strings from file /usr/share/kodi/addons/skin.estuary/language/resource.language.en_gb/strings.po
>23:23:34   6.398707 T:139653791922240    INFO: Loading skin includes from /usr/share/kodi/addons/skin.estuary/1080i/Includes.xml
>23:23:34   6.416993 T:139653791922240    INFO:   load new skin...
>23:23:34   6.417016 T:139653791922240    INFO: Loading user windows, path /usr/share/kodi/addons/skin.estuary/1080i
>23:23:34   6.419684 T:139653791922240   DEBUG: Load Skin XML: 2.70ms
>23:23:34   6.419692 T:139653791922240    INFO:   initialize new skin...
>23:23:34   6.419697 T:139653791922240   DEBUG: guilib: Fill viewport on change for solving rendering passes
>23:23:34   6.419703 T:139653791922240    INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
>23:23:34   6.421696 T:139653791922240   DEBUG: OpenBundle - Opened bundle /usr/share/kodi/addons/skin.estuary/media/Textures.xbt
>23:23:34   6.422654 T:139653791922240    INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
>23:23:34   6.423544 T:139653791922240    INFO: Loading skin file: DialogNotification.xml, load type: LOAD_ON_GUI_INIT
>23:23:34   6.423880 T:139653791922240    INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
>23:23:34   6.425252 T:139653791922240    INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
>23:23:34   6.425704 T:139653791922240    INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
>23:23:34   6.426783 T:139653791922240    INFO: Loading resource://resource.uisounds.confluence/sounds.xml
>23:23:34   6.443370 T:139653791922240    INFO:   skin loaded...
>23:23:34   6.443383 T:139653791922240   DEBUG: Activating window ID: 12997
>23:23:34   6.443392 T:139653791922240   DEBUG: ------ Window Init () ------
>23:23:34   6.443430 T:139653791922240    INFO: load splash image: /usr/share/kodi/media/Splash.png
>23:23:34   6.449434 T:139653791922240   DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
>23:23:34   6.449442 T:139653791922240 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
>23:23:34   6.449447 T:139653791922240    INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
>23:23:34   6.449516 T:139653791922240    INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
>23:23:34   6.458799 T:139653791922240    INFO: JSONRPC v7.15.1: Successfully initialized
>23:23:34   6.458808 T:139653791922240   DEBUG: ADDON: Starting service addons.
>23:23:34   6.459249 T:139653791922240   DEBUG: Activating window ID: 12999
>23:23:34   6.459260 T:139653791922240   DEBUG: ------ Window Init (Startup.xml) ------
>23:23:34   6.459264 T:139653791922240    INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
>23:23:34   6.459607 T:139653791922240   DEBUG: Activating window ID: 10000
>23:23:34   6.459615 T:139653791922240   DEBUG: ------ Window Deinit (Startup.xml) ------
>23:23:34   6.459621 T:139653791922240   DEBUG: ------ Window Init (Home.xml) ------
>23:23:34   6.459625 T:139653791922240    INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
>23:23:34   6.503501 T:139653202044672   DEBUG: Thread JobWorker start, auto delete: true
>23:23:34   6.506957 T:139653193651968   DEBUG: Previous line repeats 2 times.
>23:23:34   6.506968 T:139653193651968   DEBUG: RunQuery took 0 ms for 0 items query: select * from movie_view  ORDER BY dateAdded desc, idMovie desc LIMIT 25
>23:23:34   6.507953 T:139653202044672   DEBUG: RunQuery took 0 ms for 0 items query: select * from movie_view  WHERE (movie_view.idFile  IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
>23:23:34   6.508992 T:139653185259264   DEBUG: RunQuery took 0 ms for 0 items query: select * from movie_view  WHERE ((movie_view.playCount IS NULL OR movie_view.playCount = 0))
>23:23:34   6.510895 T:139653193651968   DEBUG: RunQuery took 0 ms for 0 items query: select * from movie_view
>23:23:34   6.513214 T:139653202044672   DEBUG: RunQuery took 1 ms for 0 items query: SELECT * FROM tvshow_view  WHERE watchedCount != 0 AND totalCount != watchedCount ORDER BY c00
>23:23:34   6.514720 T:139653185259264   DEBUG: RunQuery took 1 ms for 0 items query: select * from episode_view  ORDER BY dateAdded desc, idEpisode desc LIMIT 25
>23:23:34   6.516307 T:139653202044672   DEBUG: GetRecentlyPlayedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM albumview WHERE albumview.lastplayed IS NOT NULL ORDER BY albumview.lastplayed DESC LIMIT 25) as playedalbums JOIN albumview ON albumview.idAlbum = playedalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY albumview.lastplayed DESC, albumartistview.iorder
>23:23:34   6.517413 T:139653185259264   DEBUG: GetRecentlyAddedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM album WHERE strAlbum != '' ORDER BY idAlbum DESC LIMIT 25) AS recentalbums JOIN albumview ON albumview.idAlbum = recentalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY albumview.idAlbum desc, albumartistview.iOrder
>23:23:34   6.517449 T:139653193651968   DEBUG: RunQuery took 1 ms for 0 items query: SELECT * FROM tvshow_view  WHERE (totalCount IS NOT NULL AND totalCount > 0) AND (((CAST(tvshow_view.watchedcount as DECIMAL(5,1)) = 0)) AND ((CAST(tvshow_view.totalCount as DECIMAL(5,1)) > 0)))
>23:23:34   6.517677 T:139653791922240    INFO: removing tempfiles
>23:23:34   6.517905 T:139653791922240   DEBUG: ADDON: Starting service addons.
>23:23:34   6.518435 T:139653176866560   DEBUG: Thread LanguageInvoker start, auto delete: false
>23:23:34   6.518460 T:139653176866560    INFO: initializing python engine.
>23:23:34   6.518886 T:139653202044672   DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview  WHERE albumview.strReleaseType = 'album'
>23:23:34   6.519089 T:139653202044672   DEBUG: GetAlbumsByWhere - query took 1 ms
>23:23:34   6.519701 T:139653193651968   DEBUG: GetArtistsByWhere query: SELECT artistview.* FROM artistview  WHERE (artistview.idArtist IN (SELECT album_artist.idArtist FROM album_artist ) OR artistview.idArtist IN (SELECT song_artist.idArtist FROM song_artist WHERE song_artist.idRole = 1)) and artistview.strArtist != '' and artistview.strArtist <> 'Various artists'
>23:23:34   6.519881 T:139653193651968   DEBUG: Time to retrieve artists from dataset = 0
>23:23:34   6.519926 T:139653185259264   DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview  WHERE (albumview.strReleaseType = 'album') AND (((CAST(albumview.iTimesPlayed as DECIMAL(5,1)) = 0)))
>23:23:34   6.520181 T:139653185259264   DEBUG: GetAlbumsByWhere - query took 1 ms
>23:23:34   6.520848 T:139653791922240   DEBUG: CRepositoryUpdater: previous update at 4. 6. 2016 16:28:53, next at 5. 6. 2016 16:28:53
>23:23:34   6.520880 T:139653791922240  NOTICE: initialize done
>23:23:34   6.520888 T:139653791922240  NOTICE: Running the application...
>23:23:34   6.520899 T:139653168473856   DEBUG: Thread Timer start, auto delete: false
>23:23:34   6.521042 T:139653202044672   DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview  WHERE (albumview.strReleaseType = 'album') AND (((CAST(albumview.iTimesPlayed as DECIMAL(5,1)) > 0)))
>23:23:34   6.521122 T:139653791922240   DEBUG: no profile autoexec.py (/storage/.kodi/userdata/autoexec.py) found, skipping
>23:23:34   6.521135 T:139653791922240   DEBUG: NetworkMessage - Starting network services
>23:23:34   6.521183 T:139653202044672   DEBUG: GetAlbumsByWhere - query took 0 ms
>23:23:34   6.521791 T:139653185259264 WARNING: Create - unsupported protocol(androidapp) in androidapp://sources/apps/
>23:23:34   6.522296 T:139653791922240   DEBUG: CZeroconfAvahi::clientCallback: client is up and running
>23:23:34   6.522331 T:139653791922240  NOTICE: starting zeroconf publishing
>23:23:34   6.522482 T:139653791922240  NOTICE: CWebServer[8080]: Started
>23:23:34   6.522516 T:139653791922240  NOTICE: starting upnp client
>23:23:34   6.522556 T:139653791922240   DEBUG: GetFirstConnectedInterface no connected interface found - requery list
>23:23:34   6.523513 T:139653185259264   DEBUG: CMultiPathDirectory::GetDirectory(multipath://special%3A%2F%2Fprofile%2Fplaylists%2Fvideo/special%3A%2F%2Fprofile%2Fplaylists%2Fmixed/)
>23:23:34   6.523536 T:139653185259264   DEBUG: Getting Directory (special://profile/playlists/video)
>23:23:34   6.543658 T:139653791922240    INFO: JSONRPC Server: Successfully initialized
>23:23:34   6.543690 T:139653185259264   DEBUG: Getting Directory (special://profile/playlists/mixed)
>23:23:34   6.543707 T:139652631602944   DEBUG: Thread EventServer start, auto delete: false
>23:23:34   6.543729 T:139653791922240   DEBUG: GetFirstConnectedInterface no connected interface found - requery list
>23:23:34   6.543747 T:139652631602944  NOTICE: ES: Starting UDP Event server on 127.0.0.1:9777
>23:23:34   6.543769 T:139652631602944  NOTICE: UDP: Listening on port 9777
>23:23:34   6.543781 T:139653791922240   DEBUG: SECTION:LoadDLL(libshairplay.so.0)
>23:23:34   6.543805 T:139652623210240   DEBUG: Thread TCPServer start, auto delete: false
>23:23:34   6.543891 T:139653185259264   DEBUG: CMultiPathDirectory::MergeItems, items = 0
>23:23:34   6.543938 T:139653185259264   DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
>23:23:34   6.544213 T:139653791922240   DEBUG: Loading: libshairplay.so.0
>23:23:34   6.545252 T:139653791922240   DEBUG: GetFirstConnectedInterface no connected interface found - requery list
>23:23:34   6.545374 T:139653791922240    INFO: AIRPLAY: Cleaning up photoassetcache
>23:23:34   6.545540 T:139653791922240    INFO: AIRPLAY Server: Successfully initialized
>23:23:34   6.545566 T:139653791922240   DEBUG: GetFirstConnectedInterface no connected interface found - requery list
>23:23:34   6.545582 T:139652396738304   DEBUG: Thread AirPlayServer start, auto delete: false
>23:23:34   6.545669 T:139653185259264   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.itrack, songartistview.iOrder
>23:23:34   6.546008 T:139653185259264   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
>23:23:34   6.547639 T:139653176866560   DEBUG: CPythonInvoker(0, /usr/share/kodi/addons/service.libreelec.settings/service.py): start processing
>23:23:34   6.548894 T:139653202044672   DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webserver type: _http._tcp name:Kodi (NUCTest) port:8080
>23:23:34   6.548917 T:139653202044672   DEBUG: CZeroconfAvahi::addService() named: Kodi (NUCTest) type: _http._tcp port:8080
>23:23:34   6.549692 T:139653202044672   DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-http type: _xbmc-jsonrpc-h._tcp name:Kodi (NUCTest) port:8080
>23:23:34   6.549716 T:139653160081152   DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
>23:23:34   6.550448 T:139653202044672   DEBUG: CZeroconfAvahi::addService() named: Kodi (NUCTest) type: _xbmc-jsonrpc-h._tcp port:8080
>23:23:34   6.551301 T:139653202044672   DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:Kodi (NUCTest) port:9090
>23:23:34   6.551313 T:139653202044672   DEBUG: CZeroconfAvahi::addService() named: Kodi (NUCTest) type: _xbmc-jsonrpc._tcp port:9090
>23:23:34   6.551344 T:139653193651968   DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:Kodi (NUCTest) port:9777
>23:23:34   6.552011 T:139653202044672   DEBUG: CZeroconfAvahi::doPublishService identifier: servers.airtunes type: _raop._tcp name:000102030405@Kodi (NUCTest) port:36666
>23:23:34   6.552024 T:139653160081152   DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
>23:23:34   6.552043 T:139653193651968   DEBUG: CZeroconfAvahi::addService() named: Kodi (NUCTest) type: _xbmc-events._udp port:9777
>23:23:34   6.552702 T:139653193651968   DEBUG: CZeroconfAvahi::doPublishService identifier: servers.airplay type: _airplay._tcp name:Kodi (NUCTest) port:36667
>23:23:34   6.552720 T:139653202044672   DEBUG: CZeroconfAvahi::addService() named: 000102030405@Kodi (NUCTest) type: _raop._tcp port:36666
>23:23:34   6.553330 T:139653160081152   DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
>23:23:34   6.553401 T:139653193651968   DEBUG: Previous line repeats 2 times.
>23:23:34   6.553410 T:139653193651968   DEBUG: CZeroconfAvahi::addService() named: Kodi (NUCTest) type: _airplay._tcp port:36667
>23:23:34   6.554195 T:139653160081152   DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
>23:23:34   6.557353 T:139653176866560   DEBUG: -->Python Interpreter Initialized<--
>23:23:34   6.557384 T:139653176866560   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"
>23:23:34   6.557417 T:139653176866560   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
>23:23:34   6.557425 T:139653176866560   DEBUG: CPythonInvoker(0, /usr/share/kodi/addons/service.libreelec.settings/service.py): entering source directory /usr/share/kodi/addons/service.libreelec.settings
>23:23:34   6.557443 T:139653176866560   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
>23:23:34   6.588167 T:139653791922240   DEBUG: ------ Window Init () ------
>23:23:34   6.590339 T:139653185259264   DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
>23:23:34   6.594251 T:139653185259264   DEBUG: RunQuery took 1 ms for 0 items query: select * from movie_view  ORDER BY dateAdded desc, idMovie desc LIMIT 10
>23:23:34   6.594761 T:139653185259264   DEBUG: RunQuery took 0 ms for 0 items query: select * from episode_view  ORDER BY dateAdded desc, idEpisode desc LIMIT 10
>23:23:34   6.595230 T:139653185259264   DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view  ORDER BY dateAdded desc, idMVideo desc LIMIT 10
>23:23:34   6.595603 T:139653185259264   DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
>23:23:34   6.596657 T:139653185259264   DEBUG: GetArtistsByWhere query: SELECT COUNT(DISTINCT artistview.idArtist) FROM artistview  WHERE (artistview.idArtist IN (SELECT album_artist.idArtist FROM album_artist ) OR artistview.idArtist IN (SELECT song_artist.idArtist FROM song_artist WHERE song_artist.idRole = 1)) and artistview.strArtist != '' and artistview.strArtist <> 'Various artists'
>23:23:34   6.650633 T:139653176866560   DEBUG: ## LibreELEC Addon ## 0.8.0
>23:23:34   6.651223 T:139653176866560 WARNING: CSkinInfo: failed to load skin settings
>23:23:34   6.773224 T:139653176866560    INFO: ## LibreELEC Addon ## system::set_hostname ## NUCTest
>23:23:34   6.773508 T:139653176866560    INFO: ## LibreELEC Addon ## system::set_keyboard_layout ## us,us -model pc105
>23:23:34   6.797529 T:139653176866560    INFO: ## LibreELEC Addon ## system::updateThread ## Started
>23:23:34   6.798573 T:139653176866560    INFO: ## LibreELEC Addon ## system::set_auto_update ## manual
>23:23:34   6.798686 T:139652368815872   ERROR: ## LibreELEC Addon ## oe::load_url(http://update.libreelec.tv/updates.php?i=e4aa9afe41084ec7a31acc951227af90&d=LibreELEC&pa=Generic.x86_64&v=devel-20160604210510-#0604-g6bd3b8c&l=1
>                                            ) ## ERROR: (URLError(gaierror(-3, 'Temporary failure in name resolution'),))
>23:23:34   6.798786 T:139652368815872   ERROR: Traceback (most recent call last):
>                                              File "/home/neil/projects/LibreELEC.tv/build.LibreELEC-Generic.x86_64-8.0-devel/LibreELEC-settings-c0aa38f/.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 431, in open
>                                              File "/usr/lib/python2.7/urllib2.py", line 449, in _open
>                                              File "/usr/lib/python2.7/urllib2.py", line 409, in _call_chain
>                                              File "/usr/lib/python2.7/urllib2.py", line 1227, in http_open
>                                              File "/usr/lib/python2.7/urllib2.py", line 1197, in do_open
>                                            URLError: <urlopen error [Errno -3] Temporary failure in name resolution>
>23:23:34   6.798819 T:139652368815872   ERROR: ## LibreELEC Addon ## system::check_updates_v2 ## ERROR: (TypeError('expected string or buffer',))
>23:23:34   6.798943 T:139652368815872   ERROR: Traceback (most recent call last):
>                                              File "/home/neil/projects/LibreELEC.tv/build.LibreELEC-Generic.x86_64-8.0-devel/LibreELEC-settings-c0aa38f/.install_pkg/usr/share/kodi/addons/service.libreelec.settings/resources/lib/modules/system.py", line 537, 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
>23:23:34   6.800797 T:139652352030464    INFO: ## LibreELEC Addon ## _service_::run ## WAITING:
>23:23:34   6.803050 T:139652360423168    INFO: ## LibreELEC Addon ## xdbus Monitor started. ##
>23:23:34   7.027664 T:139653791922240   DEBUG: LogindUPowerSyscall - Received unknown signal NameAcquired
>23:24:15  36.567333 T:139653193651968   DEBUG: Thread JobWorker 139653193651968 terminating (autodelete)
>23:24:15  36.567471 T:139653202044672   DEBUG: Thread JobWorker 139653202044672 terminating (autodelete)
>23:24:15  36.600819 T:139653185259264   DEBUG: Thread JobWorker 139653185259264 terminating (autodelete)
>23:24:18  39.251534 T:139653791922240   DEBUG: LIRC: Update - NEW at 33535:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:24:18  39.251633 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:24:19  39.727539 T:139653791922240   DEBUG: LIRC: Update - NEW at 34011:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:24:19  39.743958 T:139653791922240   DEBUG: LIRC: Update - NEW at 34027:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:24:19  39.744064 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:24:19  40.061562 T:139653185259264   DEBUG: Thread JobWorker start, auto delete: true
>23:24:19  40.177113 T:139653791922240   DEBUG: Previous line repeats 1 times.
>23:24:19  40.177197 T:139653791922240   DEBUG: LIRC: Update - NEW at 34461:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:24:19  40.193798 T:139653791922240   DEBUG: LIRC: Update - NEW at 34477:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:24:19  40.193916 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:24:20  40.710480 T:139653791922240   DEBUG: LIRC: Update - NEW at 34994:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:24:20  41.558563 T:139653791922240   DEBUG: LIRC: Update - NEW at 35842:6a 0 KEY_RIGHT devinput (KEY_RIGHT)
>23:24:20  41.558651 T:139653791922240   DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
>23:24:21  42.093708 T:139653791922240   DEBUG: LIRC: Update - NEW at 36377:6a 0 KEY_RIGHT_UP devinput (KEY_RIGHT_UP)
>23:24:21  42.250790 T:139653791922240   DEBUG: LIRC: Update - NEW at 36534:160 0 KEY_OK devinput (KEY_OK)
>23:24:21  42.250877 T:139653791922240   DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
>23:24:21  42.251019 T:139653791922240   DEBUG: Activating window ID: 10025
>23:24:21  42.577854 T:139653791922240   DEBUG: ------ Window Deinit (Home.xml) ------
>23:24:21  42.594540 T:139653791922240   DEBUG: ------ Window Init (MyVideoNav.xml) ------
>23:24:21  42.594593 T:139653791922240    INFO: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY
>23:24:21  42.632030 T:139653185259264   ERROR: GetDirectory - Error getting
>23:24:21  42.632183 T:139653791922240   ERROR: Previous line repeats 1 times.
>23:24:21  42.632191 T:139653791922240   DEBUG: CGUIMediaWindow::GetDirectory (smb://HJEMMENAS/Test/)
>23:24:21  42.632198 T:139653791922240   DEBUG:   ParentPath = [smb://HJEMMENAS/Test/]
>23:24:21  42.680351 T:139653791922240   DEBUG: RunQuery took 0 ms for 8 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=1
>23:24:21  42.680824 T:139653193651968   DEBUG: Thread BackgroundLoader start, auto delete: false
>23:24:21  42.684090 T:139653185259264   DEBUG: GetImageHash - unable to stat url
>23:24:22  42.720215 T:139653193651968   DEBUG: Thread BackgroundLoader 139653193651968 terminating
>23:24:22  42.796036 T:139653791922240   DEBUG: LIRC: Update - NEW at 37079:160 0 KEY_OK_UP devinput (KEY_OK_UP)
>23:24:23  44.051998 T:139653791922240   DEBUG: LIRC: Update - NEW at 38335:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:24:23  44.052082 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:24:23  44.052650 T:139653202044672   DEBUG: GetImageHash - unable to stat url
>23:24:23  44.453243 T:139653791922240   DEBUG: LIRC: Update - NEW at 38737:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:24:23  44.494625 T:139653791922240   DEBUG: LIRC: Update - NEW at 38778:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:24:23  44.494713 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:24:24  44.828720 T:139653791922240   DEBUG: LIRC: Update - NEW at 39112:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:24:24  44.845329 T:139653791922240   DEBUG: LIRC: Update - NEW at 39129:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:24:24  44.845436 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:24:24  45.211796 T:139653791922240   DEBUG: LIRC: Update - NEW at 39495:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:24:24  45.253376 T:139653791922240   DEBUG: LIRC: Update - NEW at 39537:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:24:24  45.253464 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:24:24  45.611778 T:139653791922240   DEBUG: LIRC: Update - NEW at 39895:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:24:24  45.653347 T:139653791922240   DEBUG: LIRC: Update - NEW at 39937:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:24:24  45.653431 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:24:25  45.995140 T:139653791922240   DEBUG: LIRC: Update - NEW at 40279:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:24:25  46.011803 T:139653791922240   DEBUG: LIRC: Update - NEW at 40295:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:24:25  46.011921 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:24:25  46.519352 T:139653791922240   DEBUG: LIRC: Update - NEW at 40803:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:24:25  46.684902 T:139653791922240   DEBUG: LIRC: Update - NEW at 40968:160 0 KEY_OK devinput (KEY_OK)
>23:24:25  46.684994 T:139653791922240   DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
>23:24:25  46.685589 T:139653791922240   DEBUG: CGUIMediaWindow::GetDirectory (smb://HJEMMENAS/Test/DTS/)
>23:24:25  46.685627 T:139653791922240   DEBUG:   ParentPath = [smb://HJEMMENAS/Test/]
>23:24:25  46.693497 T:139653791922240   DEBUG: RunQuery took 0 ms for 1 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=4
>23:24:25  46.694141 T:139653193651968   DEBUG: Thread BackgroundLoader start, auto delete: false
>23:24:25  46.696785 T:139653202044672   DEBUG: GetImageHash - unable to stat url
>23:24:26  46.707714 T:139653193651968   DEBUG: Thread BackgroundLoader 139653193651968 terminating
>23:24:26  47.184113 T:139653791922240   DEBUG: LIRC: Update - NEW at 41468:160 0 KEY_OK_UP devinput (KEY_OK_UP)
>23:24:26  47.600212 T:139653791922240   DEBUG: LIRC: Update - NEW at 41884:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:24:26  47.600323 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:24:26  47.600868 T:139653185259264   DEBUG: GetImageHash - unable to stat url
>23:24:27  48.001602 T:139653791922240   DEBUG: LIRC: Update - NEW at 42285:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:24:27  48.042934 T:139653791922240   DEBUG: LIRC: Update - NEW at 42326:160 0 KEY_OK devinput (KEY_OK)
>23:24:27  48.043018 T:139653791922240   DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
>23:24:27  48.043602 T:139653791922240   DEBUG: CGUIMediaWindow::GetDirectory (smb://HJEMMENAS/Test/DTS/DTS-HD MA/)
>23:24:27  48.043648 T:139653791922240   DEBUG:   ParentPath = [smb://HJEMMENAS/Test/DTS/]
>23:24:27  48.054607 T:139653791922240   DEBUG: RunQuery took 0 ms for 6 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=6
>23:24:27  48.055481 T:139653193651968   DEBUG: Thread BackgroundLoader start, auto delete: false
>23:24:27  48.058464 T:139653185259264   DEBUG: GetImageHash - unable to stat url
>23:24:27  48.086487 T:139653193651968   DEBUG: Thread BackgroundLoader 139653193651968 terminating
>23:24:27  48.551579 T:139653791922240   DEBUG: LIRC: Update - NEW at 42835:160 0 KEY_OK_UP devinput (KEY_OK_UP)
>23:24:27  48.675575 T:139653791922240   DEBUG: LIRC: Update - NEW at 42959:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:24:27  48.675659 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:24:27  48.676205 T:139653202044672   DEBUG: GetImageHash - unable to stat url
>23:24:28  49.044399 T:139653791922240   DEBUG: LIRC: Update - NEW at 43328:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:24:28  49.060925 T:139653791922240   DEBUG: LIRC: Update - NEW at 43344:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:24:28  49.061050 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:24:28  49.427662 T:139653791922240   DEBUG: LIRC: Update - NEW at 43711:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:24:28  49.444324 T:139653791922240   DEBUG: LIRC: Update - NEW at 43728:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:24:28  49.444408 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:24:29  49.996273 T:139653791922240   DEBUG: LIRC: Update - NEW at 44280:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:24:30  50.919086 T:139653791922240   DEBUG: LIRC: Update - NEW at 45203:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:24:30  50.919170 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:24:30  51.443787 T:139653791922240   DEBUG: LIRC: Update - NEW at 45727:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:24:31  52.655216 T:139653791922240   DEBUG: LIRC: Update - NEW at 46939:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:24:31  52.655361 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:24:32  53.143818 T:139653791922240   DEBUG: LIRC: Update - NEW at 47427:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:24:32  53.185249 T:139653791922240   DEBUG: LIRC: Update - NEW at 47469:160 0 KEY_OK devinput (KEY_OK)
>23:24:32  53.185337 T:139653791922240   DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
>23:24:32  53.190708 T:139653791922240   DEBUG: OnPlayMedia smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv
>23:24:32  53.196880 T:139653791922240   DEBUG: Loading settings for smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv
>23:24:32  53.196907 T:139653791913728   DEBUG: GetMovieId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMovie from movie where idFile=46
>23:24:32  53.197010 T:139653791913728   DEBUG: GetEpisodeId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idEpisode from episode where idFile=46
>23:24:32  53.197121 T:139653791913728   DEBUG: GetMusicVideoId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMVideo from musicvideo where idFile=46
>23:24:32  53.197689 T:139653791913728   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
>23:24:32  53.197727 T:139653791913728   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
>23:24:32  53.198521 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers(smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv)
>23:24:32  53.198532 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
>23:24:32  53.198540 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
>23:24:32  53.198544 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
>23:24:32  53.198559 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
>23:24:32  53.198566 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
>23:24:32  53.198574 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
>23:24:32  53.198582 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
>23:24:32  53.198589 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
>23:24:32  53.198593 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
>23:24:32  53.198601 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
>23:24:32  53.198608 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
>23:24:32  53.198616 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
>23:24:32  53.198624 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
>23:24:32  53.198639 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
>23:24:32  53.198643 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
>23:24:32  53.198647 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
>23:24:32  53.198650 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
>23:24:32  53.199097 T:139653791922240   DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
>23:24:32  53.199112 T:139653791922240  NOTICE: VideoPlayer: Opening: smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv
>23:24:32  53.199116 T:139653791922240 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
>23:24:32  53.199139 T:139653791922240   DEBUG: LinuxRendererGL: Cleaning up GL resources
>23:24:32  53.199162 T:139653791922240   DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
>23:24:32  53.199226 T:139653193651968   DEBUG: Thread VideoPlayer start, auto delete: false
>23:24:32  53.199261 T:139653193651968  NOTICE: Creating InputStream
>23:24:32  53.201149 T:139653193651968   DEBUG: CSMBFile::Open - opened smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv, fd=10000
>23:24:32  53.201408 T:139653193651968   DEBUG: ScanForExternalSubtitles: Searching for subtitles...
>23:24:32  53.201984 T:139653193651968   DEBUG: ScanForExternalSubtitles: END (total time: 0 ms)
>23:24:32  53.201996 T:139653193651968  NOTICE: Creating Demuxer
>23:24:32  53.264336 T:139653193651968   DEBUG: Open - probing detected format [matroska,webm]
>23:24:32  53.264767 T:139653193651968   DEBUG: Open - avformat_find_stream_info starting
>23:24:32  53.314026 T:139653193651968   DEBUG: Open - av_find_stream_info finished
>23:24:32  53.314072 T:139653193651968    INFO: ffmpeg[7F038AFFD700]: Input #0, matroska,webm, smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mk':
>23:24:32  53.314087 T:139653193651968    INFO: ffmpeg[7F038AFFD700]:   Metadata:
>23:24:32  53.314110 T:139653193651968    INFO: ffmpeg[7F038AFFD700]:     encoder         : libebml v1.3.0 + libmatroska v1.4.1
>23:24:32  53.314117 T:139653193651968    INFO: ffmpeg[7F038AFFD700]:     creation_time   : 2015-04-06 12:06:06
>23:24:32  53.314133 T:139653193651968    INFO: ffmpeg[7F038AFFD700]:   Duration: 00:00:21.15, start: 0.000000, bitrate: 15012 kb/s
>23:24:32  53.314220 T:139653193651968    INFO: ffmpeg[7F038AFFD700]:     Stream #0:0(eng): Video: h264 (High), yuv420p, 1920x1080, SAR 1:1 DAR 16:9, 29.97 fps, 29.97 tbr, 1k tbn, 59.94 tbc (default)
>23:24:32  53.314251 T:139653193651968    INFO: ffmpeg[7F038AFFD700]:     Stream #0:1(eng): Audio: dts (DTS-HD MA), 96000 Hz, 7.1, s32p (24 bit) (default)
>23:24:32  53.314274 T:139653193651968   DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0
>23:24:32  53.314278 T:139653193651968   DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1
>23:24:32  53.314327 T:139653193651968  NOTICE: Opening stream: 0 source: 256
>23:24:32  53.314350 T:139653193651968  NOTICE: Creating video codec with codec id: 28
>23:24:32  53.314358 T:139653193651968   DEBUG: FactoryCodec - Video:  - Opening
>23:24:32  53.314362 T:139653193651968  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
>23:24:32  53.314438 T:139653193651968   DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
>23:24:32  53.314442 T:139653193651968   DEBUG: FactoryCodec - Video: ff-h264 - Opened
>23:24:32  53.314445 T:139653193651968   DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28
>23:24:32  53.314457 T:139653193651968  NOTICE: Creating video thread
>23:24:32  53.314491 T:139653193651968  NOTICE: Opening stream: 1 source: 256
>23:24:32  53.314499 T:139653193651968  NOTICE: Finding audio codec for: 86020
>23:24:32  53.314514 T:139653193651968   DEBUG: FactoryCodec - Audio: passthrough - Opening
>23:24:32  53.314529 T:139653193651968   DEBUG: FactoryCodec - Audio: passthrough - Failed
>23:24:32  53.314533 T:139653193651968   DEBUG: FactoryCodec - Audio: FFmpeg - Opening
>23:24:32  53.314545 T:139652136695552   DEBUG: Thread VideoPlayerVideo start, auto delete: false
>23:24:32  53.314564 T:139652136695552  NOTICE: running thread: video_thread
>23:24:32  53.314579 T:139653193651968   DEBUG: FactoryCodec - Audio: FFmpeg - Opened
>23:24:32  53.314590 T:139653193651968  NOTICE: Creating audio thread
>23:24:32  53.314663 T:139653193651968   DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv
>23:24:32  53.314674 T:139652128302848   DEBUG: Thread VideoPlayerAudio start, auto delete: false
>23:24:32  53.314693 T:139652128302848  NOTICE: running thread: CVideoPlayerAudio::Process()
>23:24:32  53.314796 T:139653193651968   DEBUG: OnPlayBackStarted: play state was 1, starting 1
>23:24:32  53.314804 T:139653193651968   DEBUG: CVideoPlayer::SetCaching - caching state 2
>23:24:32  53.314854 T:139653791922240   DEBUG: PlayFile: OpenFile succeed, play state 2
>23:24:32  53.314922 T:139652128302848   DEBUG: CDVDAudio::Pause - pausing audio stream
>23:24:32  53.314976 T:139653791922240   DEBUG: OnPlayBackStarted: play state was 2, starting 0
>23:24:32  53.315025 T:139652136695552    INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
>23:24:32  53.315044 T:139653193651968  NOTICE: CDVDMessageQueue::GetLevel() - can't determine level
>23:24:32  53.315063 T:139652136695552   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
>23:24:32  53.315094 T:139653193651968  NOTICE: CDVDMessageQueue::GetLevel() - can't determine level
>23:24:32  53.315231 T:139652136695552  NOTICE: Previous line repeats 25 times.
>23:24:32  53.315235 T:139652136695552   DEBUG: VAAPI - open decoder
>23:24:32  53.315376 T:139652128302848  NOTICE: Creating audio stream (codec id: 86020, channels: 8, sample rate: 96000, no pass-through)
>23:24:32  53.315495 T:139652128302848   DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback
>23:24:32  53.322090 T:139652136695552   DEBUG: VAAPI - initialize version 0.39
>23:24:32  53.322124 T:139652136695552   DEBUG: VAAPI - driver in use: Intel i965 driver for Intel(R) Skylake - 1.7.0
>23:24:32  53.322136 T:139652136695552   DEBUG: VAAPI - attrib 0 (get/set) min -100 max 100 value 0x0
>23:24:32  53.322144 T:139652136695552   DEBUG: VAAPI - attrib 1 (get/set) min 0 max 100 value 0x32
>23:24:32  53.322147 T:139652136695552   DEBUG: VAAPI - attrib 2 (get/set) min -180 max 180 value 0x0
>23:24:32  53.322151 T:139652136695552   DEBUG: VAAPI - attrib 3 (get/set) min 0 max 100 value 0x32
>23:24:32  53.322155 T:139652136695552   DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0
>23:24:32  53.323219 T:139652136695552   DEBUG: VAAPI - profile 0
>23:24:32  53.323227 T:139652136695552   DEBUG: VAAPI - profile 1
>23:24:32  53.323231 T:139652136695552   DEBUG: VAAPI - profile 13
>23:24:32  53.323231 T:139652136695552   DEBUG: VAAPI - profile 6
>23:24:32  53.323235 T:139652136695552   DEBUG: VAAPI - profile 7
>23:24:32  53.323238 T:139652136695552   DEBUG: VAAPI - profile 15
>23:24:32  53.323238 T:139652136695552   DEBUG: VAAPI - profile 16
>23:24:32  53.323242 T:139652136695552   DEBUG: VAAPI - profile 8
>23:24:32  53.323246 T:139652136695552   DEBUG: VAAPI - profile 9
>23:24:32  53.323250 T:139652136695552   DEBUG: VAAPI - profile 10
>23:24:32  53.323250 T:139652136695552   DEBUG: VAAPI - profile -1
>23:24:32  53.323254 T:139652136695552   DEBUG: VAAPI - profile 12
>23:24:32  53.323257 T:139652136695552   DEBUG: VAAPI - profile 14
>23:24:32  53.323257 T:139652136695552   DEBUG: VAAPI - profile 17
>23:24:32  53.323395 T:139652119910144   DEBUG: Thread Vaapi-Output start, auto delete: false
>23:24:32  53.323421 T:139652119910144  NOTICE: COutput::OnStartup: Output Thread created
>23:24:32  53.324825 T:139652119910144   DEBUG: VAAPI::COutput::CreateEGLContext - created context
>23:24:32  53.324856 T:139652119910144   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libsse4-x86_64-linux.so)
>23:24:32  53.324886 T:139652119910144   DEBUG: Loading: /usr/lib/kodi/system/libsse4-x86_64-linux.so
>23:24:32  53.328831 T:139652119910144   DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created
>23:24:32  53.328869 T:139652136695552   DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vaapi
>23:24:32  53.330532 T:139652136695552   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
>23:24:32  53.331764 T:139653202044672   DEBUG: Previous line repeats 1 times.
>23:24:32  53.331791 T:139653202044672   DEBUG: CGUIInfoManager::SetCurrentMovie(smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv)
>23:24:32  53.332283 T:139652136695552   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
>23:24:32  53.334557 T:139653202044672   DEBUG: Previous line repeats 1 times.
>23:24:32  53.334591 T:139653202044672   DEBUG: GetMovieId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMovie from movie where idFile=46
>23:24:32  53.334705 T:139653202044672   DEBUG: Previous line repeats 1 times.
>23:24:32  53.334717 T:139653202044672   DEBUG: GetEpisodeId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idEpisode from episode where idFile=46
>23:24:32  53.334866 T:139653791913728   DEBUG: Previous line repeats 1 times.
>23:24:32  53.334881 T:139653791913728   DEBUG: GetMusicVideoId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMVideo from musicvideo where idFile=46
>23:24:32  53.335693 T:139653791913728   DEBUG: Previous line repeats 1 times.
>23:24:32  53.335705 T:139653791913728   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
>23:24:32  53.335728 T:139653791913728   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
>23:24:32  53.345524 T:139653763618560    INFO: CActiveAESink::OpenSink - initialize sink
>23:24:32  53.348354 T:139652136695552   DEBUG: CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 29.97. format: VAAPI
>23:24:32  53.349689 T:139653791922240   DEBUG: DeleteRenderer - deleting renderer
>23:24:32  53.349709 T:139653791922240   DEBUG: LinuxRendererGL: Cleaning up GL resources
>23:24:32  53.349834 T:139653791922240   DEBUG: Previous line repeats 1 times.
>23:24:32  53.349838 T:139653791922240   DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
>23:24:32  53.349869 T:139653791922240   DEBUG: GL: Requested render method: 0
>23:24:32  53.350456 T:139653791922240   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
>23:24:32  53.350555 T:139653791922240  NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
>23:24:32  53.351051 T:139653791922240   DEBUG: GL: Vertex Shader compilation log:
>23:24:32  53.351059 T:139653791922240   DEBUG: GL: Vertex Shader compiled successfully
>23:24:32  53.351921 T:139653791922240   DEBUG: GL: Pixel Shader compilation log:
>23:24:32  53.351929 T:139653791922240   DEBUG: GL: Fragment Shader compiled successfully
>23:24:32  53.359661 T:139653791922240   DEBUG: GL: Vertex Shader compilation log:
>23:24:32  53.359673 T:139653791922240   DEBUG: GL: Vertex Shader compiled successfully
>23:24:32  53.359951 T:139653791922240   DEBUG: GL: Pixel Shader compilation log:
>23:24:32  53.359959 T:139653791922240   DEBUG: GL: Fragment Shader compiled successfully
>23:24:32  53.362564 T:139653791922240  NOTICE: GL: NPOT texture support detected
>23:24:32  53.362572 T:139653791922240  NOTICE: GL: Using GL_ARB_pixel_buffer_object
>23:24:32  53.362576 T:139653791922240  NOTICE: Using GL_TEXTURE_2D
>23:24:32  53.362579 T:139653791922240   DEBUG: CRenderManager::Configure - 4
>23:24:32  53.362770 T:139653791922240   DEBUG: GL: Requested render method: 0
>23:24:32  53.362812 T:139653791922240   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
>23:24:32  53.362858 T:139653791922240  NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
>23:24:32  53.363323 T:139653791922240   DEBUG: GL: Vertex Shader compilation log:
>23:24:32  53.363335 T:139653791922240   DEBUG: GL: Vertex Shader compiled successfully
>23:24:32  53.364212 T:139653791922240   DEBUG: GL: Pixel Shader compilation log:
>23:24:32  53.364223 T:139653791922240   DEBUG: GL: Fragment Shader compiled successfully
>23:24:32  53.364857 T:139653193651968   DEBUG: CVideoPlayer::HandleMessages - player started 2
>23:24:32  53.372471 T:139653791922240  NOTICE: GL: NPOT texture support detected
>23:24:32  53.372478 T:139653791922240  NOTICE: GL: Using GL_ARB_pixel_buffer_object
>23:24:32  53.377098 T:139653791922240   DEBUG: Activating window ID: 12005
>23:24:32  53.377396 T:139653791922240   DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
>23:24:32  53.377823 T:139653791922240   DEBUG: ------ Window Init (VideoFullScreen.xml) ------
>23:24:32  53.377834 T:139653791922240    INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
>23:24:32  53.547291 T:139653763618560   DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0
>23:24:32  53.547363 T:139653763618560    INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0"
>23:24:32  53.552612 T:139653763618560    INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x0a"
>23:24:32  53.553352 T:139653763618560    INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
>23:24:32  53.553394 T:139653763618560    INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
>23:24:32  53.553410 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 4800, bufferSize 19200
>23:24:32  53.560104 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 4800, bufferSize 19200
>23:24:32  53.560173 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
>23:24:32  53.561417 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
>23:24:32  53.561440 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
>23:24:32  53.561455 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)
>23:24:32  53.561470 T:139653763618560   DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
>23:24:32  53.561485 T:139653763618560   DEBUG:   Output Device : HDA Intel PCH
>23:24:32  53.561497 T:139653763618560   DEBUG:   Sample Rate   : 96000
>23:24:32  53.561508 T:139653763618560   DEBUG:   Sample Format : AE_FMT_S32NE
>23:24:32  53.561520 T:139653763618560   DEBUG:   Channel Count : 2
>23:24:32  53.561535 T:139653763618560   DEBUG:   Channel Layout: FL,FR
>23:24:32  53.561546 T:139653763618560   DEBUG:   Frames        : 4800
>23:24:32  53.561558 T:139653763618560   DEBUG:   Frame Size    : 8
>23:24:32  53.568249 T:139653772986112   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
>23:24:32  53.574738 T:139653193651968   DEBUG: Previous line repeats 1 times.
>23:24:32  53.574783 T:139653193651968   DEBUG: CVideoPlayer::HandleMessages - player started 1
>23:24:32  53.574802 T:139653193651968   DEBUG: CVideoPlayer::SetCaching - caching state 3
>23:24:32  53.574825 T:139653193651968   DEBUG: CVideoPlayer::SetCaching - caching state 0
>23:24:32  53.574852 T:139653193651968   DEBUG: VideoPlayer::Sync - Audio - pts: 106000.000000, cache: 309371.983782, totalcache: 600000.023842
>23:24:32  53.574871 T:139653193651968   DEBUG: VideoPlayer::Sync - Video - pts: 0.000000, cache: 50000.000000, totalcache: 100000.000000
>23:24:32  53.574966 T:139652136695552   DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-203371.983782)
>23:24:32  53.575031 T:139652136695552   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
>23:24:32  53.585045 T:139652128302848   DEBUG: Previous line repeats 2 times.
>23:24:32  53.585083 T:139652128302848   DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-203371.983782)
>23:24:32  53.585114 T:139652128302848   DEBUG: CDVDAudio::Resume - resume audio stream
>23:24:32  53.586044 T:139653772986112   DEBUG: ActiveAE - start sync of audio stream
>23:24:32  53.674644 T:139653791922240   DEBUG: LIRC: Update - NEW at 47958:160 0 KEY_OK_UP devinput (KEY_OK_UP)
>23:24:33  53.715733 T:139653772986112   DEBUG: ActiveAE::SyncStream - average error of -8.948150, start adjusting
>23:24:33  53.715809 T:139653772986112   DEBUG: ActiveAE::SyncStream - average error -0.948150 below threshold of 30.000000
>23:24:34  54.768196 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-29895.230418, adjusted:-29895.230418
>23:24:35  55.768101 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-1642.039271, adjusted:-1642.039271
>23:24:36  56.768360 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:1565.667907, adjusted:1565.667907
>23:24:36  57.675922 T:139652136695552   DEBUG: CPullupCorrection: detected pattern of length 1: 33366.67, frameduration: 33366.666667
>23:24:37  57.768513 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:153.237385, adjusted:153.237385
>23:24:38  58.768600 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-120.184771, adjusted:-120.184771
>23:24:39  59.768600 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:52.656900, adjusted:52.656900
>23:24:40  60.768654 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:50.971467, adjusted:50.971467
>23:24:41  61.868088 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-195.293877, adjusted:-195.293877
>23:24:42  62.868172 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:40.540066, adjusted:40.540066
>23:24:43  63.868607 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:13.898097, adjusted:13.898097
>23:24:44  64.868752 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:98.464752, adjusted:98.464752
>23:24:45  65.868301 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:7.310599, adjusted:7.310599
>23:24:46  66.868149 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-8.360137, adjusted:-8.360137
>23:24:47  67.868462 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-41.609501, adjusted:-41.609501
>23:24:48  68.868317 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:49.198883, adjusted:49.198883
>23:24:49  69.868027 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-199.086683, adjusted:-199.086683
>23:24:50  70.868477 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:152.458050, adjusted:152.458050
>23:24:50  71.094620 T:139652136695552   DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 1, Bufferlevel: 3, dropped: 1
>23:24:50  71.459763 T:139652136695552   DEBUG: Previous line repeats 1 times.
>23:24:50  71.459816 T:139652136695552   DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 2, Bufferlevel: 3, dropped: 1
>23:24:51  71.866928 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:1.700400, adjusted:1.700400
>23:24:52  72.861275 T:139652136695552    INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 29.970030 fps
>23:24:52  72.867256 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:0.111683, adjusted:0.111683
>23:24:53  73.867416 T:139652128302848   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:2.863783, adjusted:2.863783
>23:24:53  74.366470 T:139652128302848  NOTICE: CDVDMessageQueue::GetLevel() - can't determine level
>23:24:53  74.368279 T:139652128302848  NOTICE: Previous line repeats 2 times.
>23:24:53  74.368317 T:139652128302848   DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_EOF
>23:24:53  74.368332 T:139652128302848  NOTICE: CVideoPlayerAudio::Process - stream stalled
>23:24:53  74.393967 T:139653193651968  NOTICE: CVideoPlayer::OnExit()
>23:24:53  74.394058 T:139653193651968  NOTICE: VideoPlayer: eof, waiting for queues to empty
>23:24:53  74.394089 T:139653193651968  NOTICE: Closing stream player 1
>23:24:53  74.394104 T:139653193651968  NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty
>23:24:53  74.394264 T:139652128302848   DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
>23:24:53  74.394394 T:139653193651968  NOTICE: Waiting for audio thread to exit
>23:24:53  74.394447 T:139652128302848   ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
>23:24:53  74.394470 T:139652128302848  NOTICE: thread end: CVideoPlayerAudio::OnExit()
>23:24:53  74.394508 T:139653193651968  NOTICE: Closing audio device
>23:24:53  74.394569 T:139652128302848   DEBUG: Thread VideoPlayerAudio 139652128302848 terminating
>23:24:53  74.615837 T:139653772986112   DEBUG: CActiveAE::DiscardStream - audio stream deleted
>23:24:53  74.615913 T:139653772986112   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
>23:24:53  74.616127 T:139653193651968   DEBUG: Previous line repeats 1 times.
>23:24:53  74.616165 T:139653193651968  NOTICE: Deleting audio codec
>23:24:53  74.616234 T:139653193651968  NOTICE: Closing stream player 2
>23:24:53  74.616264 T:139653193651968  NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty
>23:24:53  74.616470 T:139652136695552   DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
>23:24:53  74.616539 T:139653193651968  NOTICE: waiting for video thread to exit
>23:24:53  74.616638 T:139652136695552   ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
>23:24:53  74.616707 T:139652136695552  NOTICE: thread end: video_thread
>23:24:53  74.616745 T:139652136695552   DEBUG: Thread VideoPlayerVideo 139652136695552 terminating
>23:24:53  74.616791 T:139653193651968  NOTICE: deleting video codec
>23:24:53  74.616966 T:139653193651968   DEBUG: VAAPI::Release pre-cleanup
>23:24:53  74.623100 T:139653193651968   DEBUG: CSMBFile::Close closing fd 10000
>23:24:53  74.623466 T:139653193651968   DEBUG: OnPlayBackEnded: play state was 2, starting 0
>23:24:53  74.623505 T:139653193651968   DEBUG: Thread VideoPlayer 139653193651968 terminating
>23:24:53  74.624779 T:139653791913728   DEBUG: GetMovieId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMovie from movie where idFile=46
>23:24:53  74.624886 T:139653791913728   DEBUG: GetEpisodeId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idEpisode from episode where idFile=46
>23:24:53  74.624969 T:139653791913728   DEBUG: GetMusicVideoId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMVideo from musicvideo where idFile=46
>23:24:53  74.625374 T:139653791913728   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
>23:24:53  74.625404 T:139653791913728   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
>23:24:53  74.627335 T:139653791922240   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
>23:24:53  74.627357 T:139653791922240   DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
>23:24:53  74.628967 T:139653791922240   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
>23:24:53  74.630447 T:139653791922240   DEBUG: ------ Window Init (MyVideoNav.xml) ------
>23:24:53  74.630951 T:139653791922240   DEBUG: CGUIMediaWindow::GetDirectory (smb://HJEMMENAS/Test/DTS/DTS-HD MA/)
>23:24:53  74.630966 T:139653791922240   DEBUG:   ParentPath = [smb://HJEMMENAS/Test/DTS/DTS-HD MA/]
>23:24:53  74.636147 T:139653791922240   DEBUG: RunQuery took 0 ms for 6 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=6
>23:24:53  74.636482 T:139653193651968   DEBUG: Thread BackgroundLoader start, auto delete: false
>23:24:53  74.646065 T:139653193651968   DEBUG: Thread BackgroundLoader 139653193651968 terminating
>23:24:53  74.656250 T:139653185259264   DEBUG: GetImageHash - unable to stat url
>23:24:53  74.663429 T:139653791922240  NOTICE: CVideoPlayer::CloseFile()
>23:24:53  74.663445 T:139653791922240  NOTICE: VideoPlayer: waiting for threads to exit
>23:24:53  74.663445 T:139653791922240  NOTICE: VideoPlayer: finished waiting
>23:24:53  74.663475 T:139653791922240   DEBUG: DeleteRenderer - deleting renderer
>23:24:53  74.663483 T:139653791922240  NOTICE: VAAPI::Close
>23:24:53  74.665695 T:139652119910144  NOTICE: COutput::OnExit: Output Thread terminated
>23:24:53  74.665733 T:139652119910144   DEBUG: Thread Vaapi-Output 139652119910144 terminating
>23:24:53  74.666039 T:139653791922240   DEBUG: VAAPI::FiniVAAPIOutput destroying 1 video surfaces
>23:24:53  74.666245 T:139653791922240  NOTICE: VAAPI::Close - closing decoder context
>23:24:53  74.667236 T:139653791922240   DEBUG: LinuxRendererGL: Cleaning up GL resources
>23:24:53  74.667274 T:139653791922240  NOTICE: CVideoPlayer::CloseFile()
>23:24:53  74.667282 T:139653791922240  NOTICE: VideoPlayer: waiting for threads to exit
>23:24:53  74.667282 T:139653791922240  NOTICE: VideoPlayer: finished waiting
>23:24:53  74.667732 T:139653791922240   DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
>23:24:54  74.965584 T:139653763618560    INFO: CActiveAESink::OpenSink - initialize sink
>23:24:54  75.078720 T:139653202044672   DEBUG: DoWork - Saving file state for video item smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv
>23:24:54  75.083237 T:139653202044672   DEBUG: DoWork - Marking video item smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv as watched
>23:24:54  75.090714 T:139653791913728   DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc
>23:24:54  75.090736 T:139653791913728   DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate
>23:24:54  75.109947 T:139653185259264   DEBUG: GetImageHash - unable to stat url
>23:24:54  75.167419 T:139653763618560   DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0
>23:24:54  75.167488 T:139653763618560    INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0"
>23:24:54  75.171585 T:139653763618560    INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"
>23:24:54  75.171791 T:139653763618560    INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
>23:24:54  75.171806 T:139653763618560    INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
>23:24:54  75.171806 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820
>23:24:54  75.176758 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820
>23:24:54  75.176773 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
>23:24:54  75.177856 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
>23:24:54  75.177856 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
>23:24:54  75.177864 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)
>23:24:54  75.177872 T:139653763618560   DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
>23:24:54  75.177872 T:139653763618560   DEBUG:   Output Device : HDA Intel PCH
>23:24:54  75.177887 T:139653763618560   DEBUG:   Sample Rate   : 44100
>23:24:54  75.177887 T:139653763618560   DEBUG:   Sample Format : AE_FMT_S32NE
>23:24:54  75.177895 T:139653763618560   DEBUG:   Channel Count : 2
>23:24:54  75.177895 T:139653763618560   DEBUG:   Channel Layout: FL,FR
>23:24:54  75.177902 T:139653763618560   DEBUG:   Frames        : 2205
>23:24:54  75.177902 T:139653763618560   DEBUG:   Frame Size    : 8
>23:24:54  75.178680 T:139653772986112   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
>23:24:55  75.909943 T:139653791922240   DEBUG: Previous line repeats 1 times.
>23:24:55  75.910019 T:139653791922240   DEBUG: LIRC: Update - NEW at 70193:160 0 KEY_OK devinput (KEY_OK)
>23:24:55  75.910072 T:139653791922240   DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
>23:24:55  75.915390 T:139653791922240   DEBUG: OnPlayMedia smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv
>23:24:55  75.915680 T:139653791913728   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
>23:24:55  75.915764 T:139653791913728   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
>23:24:55  75.918503 T:139653791913728   DEBUG: GetMovieId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMovie from movie where idFile=46
>23:24:55  75.918671 T:139653791913728   DEBUG: GetEpisodeId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idEpisode from episode where idFile=46
>23:24:55  75.918716 T:139653791922240   DEBUG: Loading settings for smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv
>23:24:55  75.918846 T:139653791913728   DEBUG: GetMusicVideoId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMVideo from musicvideo where idFile=46
>23:24:55  75.919640 T:139653791913728   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
>23:24:55  75.919670 T:139653791913728   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
>23:24:55  75.920944 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers(smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv)
>23:24:55  75.920952 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
>23:24:55  75.920967 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
>23:24:55  75.920967 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
>23:24:55  75.920982 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
>23:24:55  75.920998 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
>23:24:55  75.921005 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
>23:24:55  75.921013 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
>23:24:55  75.921028 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
>23:24:55  75.921028 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
>23:24:55  75.921036 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
>23:24:55  75.921043 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
>23:24:55  75.921059 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
>23:24:55  75.921066 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
>23:24:55  75.921066 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
>23:24:55  75.921074 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
>23:24:55  75.921082 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
>23:24:55  75.921082 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
>23:24:55  75.921623 T:139653791922240   DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
>23:24:55  75.921646 T:139653791922240  NOTICE: VideoPlayer: Opening: smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv
>23:24:55  75.921654 T:139653791922240 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
>23:24:55  75.921677 T:139653791922240   DEBUG: LinuxRendererGL: Cleaning up GL resources
>23:24:55  75.921715 T:139653791922240   DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
>23:24:55  75.921791 T:139652119910144   DEBUG: Thread VideoPlayer start, auto delete: false
>23:24:55  75.921837 T:139652119910144  NOTICE: Creating InputStream
>23:24:55  75.923630 T:139652119910144   DEBUG: CSMBFile::Open - opened smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv, fd=10000
>23:24:55  75.923920 T:139652119910144   DEBUG: ScanForExternalSubtitles: Searching for subtitles...
>23:24:55  75.924629 T:139652119910144   DEBUG: ScanForExternalSubtitles: END (total time: 1 ms)
>23:24:55  75.924644 T:139652119910144  NOTICE: Creating Demuxer
>23:24:55  75.925575 T:139652119910144   DEBUG: Open - probing detected format [matroska,webm]
>23:24:55  75.925644 T:139652119910144   DEBUG: Open - avformat_find_stream_info starting
>23:24:55  75.936264 T:139652119910144   DEBUG: Open - av_find_stream_info finished
>23:24:55  75.936317 T:139652119910144    INFO: ffmpeg[7F034AFFD700]: Input #0, matroska,webm, smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mk':
>23:24:55  75.936325 T:139652119910144    INFO: ffmpeg[7F034AFFD700]:   Metadata:
>23:24:55  75.936340 T:139652119910144    INFO: ffmpeg[7F034AFFD700]:     encoder         : libebml v1.3.0 + libmatroska v1.4.1
>23:24:55  75.936348 T:139652119910144    INFO: ffmpeg[7F034AFFD700]:     creation_time   : 2015-04-06 12:06:06
>23:24:55  75.936356 T:139652119910144    INFO: ffmpeg[7F034AFFD700]:   Duration: 00:00:21.15, start: 0.000000, bitrate: 15012 kb/s
>23:24:55  75.936386 T:139652119910144    INFO: ffmpeg[7F034AFFD700]:     Stream #0:0(eng): Video: h264 (High), yuv420p, 1920x1080, SAR 1:1 DAR 16:9, 29.97 fps, 29.97 tbr, 1k tbn, 59.94 tbc (default)
>23:24:55  75.936409 T:139652119910144    INFO: ffmpeg[7F034AFFD700]:     Stream #0:1(eng): Audio: dts (DTS-HD MA), 96000 Hz, 7.1, s32p (24 bit) (default)
>23:24:55  75.936417 T:139652119910144   DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0
>23:24:55  75.936417 T:139652119910144   DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1
>23:24:55  75.936462 T:139652119910144  NOTICE: Opening stream: 0 source: 256
>23:24:55  75.936485 T:139652119910144  NOTICE: Creating video codec with codec id: 28
>23:24:55  75.936493 T:139652119910144   DEBUG: FactoryCodec - Video:  - Opening
>23:24:55  75.936501 T:139652119910144  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
>23:24:55  75.936569 T:139652119910144   DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
>23:24:55  75.936577 T:139652119910144   DEBUG: FactoryCodec - Video: ff-h264 - Opened
>23:24:55  75.936584 T:139652119910144   DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28
>23:24:55  75.936592 T:139652119910144  NOTICE: Creating video thread
>23:24:55  75.936623 T:139652119910144  NOTICE: Opening stream: 1 source: 256
>23:24:55  75.936630 T:139652119910144  NOTICE: Finding audio codec for: 86020
>23:24:55  75.936646 T:139652119910144   DEBUG: FactoryCodec - Audio: passthrough - Opening
>23:24:55  75.936653 T:139652119910144   DEBUG: FactoryCodec - Audio: passthrough - Failed
>23:24:55  75.936661 T:139652119910144   DEBUG: FactoryCodec - Audio: FFmpeg - Opening
>23:24:55  75.936684 T:139653193651968   DEBUG: Thread VideoPlayerVideo start, auto delete: false
>23:24:55  75.936699 T:139653193651968  NOTICE: running thread: video_thread
>23:24:55  75.936714 T:139652119910144   DEBUG: FactoryCodec - Audio: FFmpeg - Opened
>23:24:55  75.936722 T:139652119910144  NOTICE: Creating audio thread
>23:24:55  75.936798 T:139652136695552   DEBUG: Thread VideoPlayerAudio start, auto delete: false
>23:24:55  75.936874 T:139652119910144   DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv
>23:24:55  75.936943 T:139652136695552  NOTICE: running thread: CVideoPlayerAudio::Process()
>23:24:55  75.937050 T:139652119910144   DEBUG: OnPlayBackStarted: play state was 1, starting 1
>23:24:55  75.937065 T:139652119910144   DEBUG: CVideoPlayer::SetCaching - caching state 2
>23:24:55  75.937080 T:139653791922240   DEBUG: PlayFile: OpenFile succeed, play state 2
>23:24:55  75.937096 T:139653791922240   DEBUG: OnPlayBackStarted: play state was 2, starting 0
>23:24:55  75.937111 T:139652136695552   DEBUG: CDVDAudio::Pause - pausing audio stream
>23:24:55  75.937164 T:139653193651968    INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
>23:24:55  75.937180 T:139653193651968   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
>23:24:55  75.937233 T:139652119910144  NOTICE: CDVDMessageQueue::GetLevel() - can't determine level
>23:24:55  75.937263 T:139653193651968  NOTICE: Previous line repeats 4 times.
>23:24:55  75.937263 T:139653193651968   DEBUG: VAAPI - open decoder
>23:24:55  75.937279 T:139652119910144  NOTICE: CDVDMessageQueue::GetLevel() - can't determine level
>23:24:55  75.937454 T:139652136695552  NOTICE: Previous line repeats 21 times.
>23:24:55  75.937462 T:139652136695552  NOTICE: Creating audio stream (codec id: 86020, channels: 8, sample rate: 96000, no pass-through)
>23:24:55  75.937500 T:139652136695552   DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback
>23:24:55  75.938683 T:139653193651968   DEBUG: VAAPI - initialize version 0.39
>23:24:55  75.938690 T:139653193651968   DEBUG: VAAPI - driver in use: Intel i965 driver for Intel(R) Skylake - 1.7.0
>23:24:55  75.938698 T:139653193651968   DEBUG: VAAPI - attrib 0 (get/set) min -100 max 100 value 0x0
>23:24:55  75.938705 T:139653193651968   DEBUG: VAAPI - attrib 1 (get/set) min 0 max 100 value 0x32
>23:24:55  75.938713 T:139653193651968   DEBUG: VAAPI - attrib 2 (get/set) min -180 max 180 value 0x0
>23:24:55  75.938713 T:139653193651968   DEBUG: VAAPI - attrib 3 (get/set) min 0 max 100 value 0x32
>23:24:55  75.938721 T:139653193651968   DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0
>23:24:55  75.938721 T:139653193651968   DEBUG: VAAPI - profile 0
>23:24:55  75.938728 T:139653193651968   DEBUG: VAAPI - profile 1
>23:24:55  75.938736 T:139653193651968   DEBUG: VAAPI - profile 13
>23:24:55  75.938736 T:139653193651968   DEBUG: VAAPI - profile 6
>23:24:55  75.938744 T:139653193651968   DEBUG: VAAPI - profile 7
>23:24:55  75.938744 T:139653193651968   DEBUG: VAAPI - profile 15
>23:24:55  75.938751 T:139653193651968   DEBUG: VAAPI - profile 16
>23:24:55  75.938751 T:139653193651968   DEBUG: VAAPI - profile 8
>23:24:55  75.938759 T:139653193651968   DEBUG: VAAPI - profile 9
>23:24:55  75.938759 T:139653193651968   DEBUG: VAAPI - profile 10
>23:24:55  75.938766 T:139653193651968   DEBUG: VAAPI - profile -1
>23:24:55  75.938766 T:139653193651968   DEBUG: VAAPI - profile 12
>23:24:55  75.938774 T:139653193651968   DEBUG: VAAPI - profile 14
>23:24:55  75.938774 T:139653193651968   DEBUG: VAAPI - profile 17
>23:24:55  75.938866 T:139652128302848   DEBUG: Thread Vaapi-Output start, auto delete: false
>23:24:55  75.938881 T:139652128302848  NOTICE: COutput::OnStartup: Output Thread created
>23:24:55  75.940666 T:139652128302848   DEBUG: VAAPI::COutput::CreateEGLContext - created context
>23:24:55  75.944862 T:139652128302848   DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created
>23:24:55  75.944931 T:139653193651968   DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vaapi
>23:24:55  75.946457 T:139653193651968   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
>23:24:55  75.949287 T:139653202044672   DEBUG: Previous line repeats 3 times.
>23:24:55  75.949364 T:139653202044672   DEBUG: CGUIInfoManager::SetCurrentMovie(smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv)
>23:24:55  75.952667 T:139653202044672   DEBUG: GetMovieId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMovie from movie where idFile=46
>23:24:55  75.952843 T:139653202044672   DEBUG: GetEpisodeId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idEpisode from episode where idFile=46
>23:24:55  75.952972 T:139653202044672   DEBUG: GetMusicVideoId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMVideo from musicvideo where idFile=46
>23:24:55  75.953102 T:139653791913728   DEBUG: GetMovieId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMovie from movie where idFile=46
>23:24:55  75.953247 T:139653791913728   DEBUG: GetEpisodeId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idEpisode from episode where idFile=46
>23:24:55  75.953476 T:139653791913728   DEBUG: GetMusicVideoId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMVideo from musicvideo where idFile=46
>23:24:55  75.954224 T:139653791913728   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
>23:24:55  75.954262 T:139653791913728   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
>23:24:55  75.956177 T:139653193651968   DEBUG: CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 29.97. format: VAAPI
>23:24:55  75.958138 T:139653791922240   DEBUG: DeleteRenderer - deleting renderer
>23:24:55  75.958160 T:139653791922240   DEBUG: LinuxRendererGL: Cleaning up GL resources
>23:24:55  75.962402 T:139653791922240   DEBUG: Previous line repeats 1 times.
>23:24:55  75.962425 T:139653791922240   DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
>23:24:55  75.962456 T:139653791922240   DEBUG: GL: Requested render method: 0
>23:24:55  75.962517 T:139653791922240   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
>23:24:55  75.962570 T:139653791922240  NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
>23:24:55  75.963348 T:139653791922240   DEBUG: GL: Vertex Shader compilation log:
>23:24:55  75.963364 T:139653791922240   DEBUG: GL: Vertex Shader compiled successfully
>23:24:55  75.964722 T:139653791922240   DEBUG: GL: Pixel Shader compilation log:
>23:24:55  75.964737 T:139653791922240   DEBUG: GL: Fragment Shader compiled successfully
>23:24:55  75.977219 T:139653791922240   DEBUG: GL: Vertex Shader compilation log:
>23:24:55  75.977234 T:139653791922240   DEBUG: GL: Vertex Shader compiled successfully
>23:24:55  75.977661 T:139653791922240   DEBUG: GL: Pixel Shader compilation log:
>23:24:55  75.977676 T:139653791922240   DEBUG: GL: Fragment Shader compiled successfully
>23:24:55  75.978439 T:139653763618560    INFO: CActiveAESink::OpenSink - initialize sink
>23:24:55  75.981865 T:139653791922240  NOTICE: GL: NPOT texture support detected
>23:24:55  75.981880 T:139653791922240  NOTICE: GL: Using GL_ARB_pixel_buffer_object
>23:24:55  75.981888 T:139653791922240  NOTICE: Using GL_TEXTURE_2D
>23:24:55  75.981888 T:139653791922240   DEBUG: CRenderManager::Configure - 4
>23:24:55  75.982010 T:139653791922240   DEBUG: GL: Requested render method: 0
>23:24:55  75.982079 T:139653791922240   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
>23:24:55  75.982124 T:139653791922240  NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
>23:24:55  75.982758 T:139653791922240   DEBUG: GL: Vertex Shader compilation log:
>23:24:55  75.982773 T:139653791922240   DEBUG: GL: Vertex Shader compiled successfully
>23:24:55  75.983223 T:139652119910144   DEBUG: CVideoPlayer::HandleMessages - player started 2
>23:24:55  75.983955 T:139653791922240   DEBUG: GL: Pixel Shader compilation log:
>23:24:55  75.983971 T:139653791922240   DEBUG: GL: Fragment Shader compiled successfully
>23:24:55  75.992805 T:139653791922240  NOTICE: GL: NPOT texture support detected
>23:24:55  75.992813 T:139653791922240  NOTICE: GL: Using GL_ARB_pixel_buffer_object
>23:24:55  75.995354 T:139653791922240   DEBUG: Activating window ID: 12005
>23:24:55  75.995934 T:139653791922240   DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
>23:24:55  75.996178 T:139653791922240   DEBUG: ------ Window Init (VideoFullScreen.xml) ------
>23:24:55  76.179306 T:139653763618560   DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0
>23:24:55  76.179367 T:139653763618560    INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0"
>23:24:55  76.184448 T:139653763618560    INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x0a"
>23:24:55  76.185165 T:139653763618560    INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
>23:24:55  76.185204 T:139653763618560    INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
>23:24:55  76.185219 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 4800, bufferSize 19200
>23:24:55  76.190041 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 4800, bufferSize 19200
>23:24:55  76.190079 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
>23:24:55  76.191437 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
>23:24:55  76.191467 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
>23:24:55  76.191483 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)
>23:24:55  76.191498 T:139653763618560   DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
>23:24:55  76.191513 T:139653763618560   DEBUG:   Output Device : HDA Intel PCH
>23:24:55  76.191528 T:139653763618560   DEBUG:   Sample Rate   : 96000
>23:24:55  76.191544 T:139653763618560   DEBUG:   Sample Format : AE_FMT_S32NE
>23:24:55  76.191551 T:139653763618560   DEBUG:   Channel Count : 2
>23:24:55  76.191566 T:139653763618560   DEBUG:   Channel Layout: FL,FR
>23:24:55  76.191574 T:139653763618560   DEBUG:   Frames        : 4800
>23:24:55  76.191589 T:139653763618560   DEBUG:   Frame Size    : 8
>23:24:55  76.196999 T:139653772986112   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
>23:24:55  76.209396 T:139652119910144   DEBUG: Previous line repeats 1 times.
>23:24:55  76.209442 T:139652119910144   DEBUG: CVideoPlayer::HandleMessages - player started 1
>23:24:55  76.209457 T:139652119910144   DEBUG: CVideoPlayer::SetCaching - caching state 3
>23:24:55  76.209496 T:139652119910144   DEBUG: CVideoPlayer::SetCaching - caching state 0
>23:24:55  76.209518 T:139652119910144   DEBUG: VideoPlayer::Sync - Audio - pts: 95000.000000, cache: 300955.401367, totalcache: 600000.023842
>23:24:55  76.209541 T:139652119910144   DEBUG: VideoPlayer::Sync - Video - pts: 0.000000, cache: 50000.000000, totalcache: 100000.000000
>23:24:55  76.209602 T:139653193651968   DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-205955.401367)
>23:24:55  76.209648 T:139653193651968   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
>23:24:55  76.219711 T:139652136695552   DEBUG: Previous line repeats 2 times.
>23:24:55  76.219757 T:139652136695552   DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-205955.401367)
>23:24:55  76.219788 T:139652136695552   DEBUG: CDVDAudio::Resume - resume audio stream
>23:24:55  76.220177 T:139653772986112   DEBUG: ActiveAE - start sync of audio stream
>23:24:55  76.346001 T:139653772986112   DEBUG: ActiveAE::SyncStream - average error of -1.995375, start adjusting
>23:24:55  76.346069 T:139653772986112   DEBUG: ActiveAE::SyncStream - average error -0.995375 below threshold of 30.000000
>23:24:55  76.424736 T:139653791922240   DEBUG: LIRC: Update - NEW at 70708:160 0 KEY_OK_UP devinput (KEY_OK_UP)
>23:24:56  77.398979 T:139652136695552   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-29910.244817, adjusted:-29910.244817
>23:24:57  78.399178 T:139652136695552   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-1644.581824, adjusted:-1644.581824
>23:24:58  79.398544 T:139652136695552   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:1564.905200, adjusted:1564.905200
>23:24:58  79.591202 T:139653791922240   DEBUG: LIRC: Update - NEW at 73875:80 0 KEY_STOP devinput (KEY_STOP)
>23:24:58  79.591331 T:139653791922240   DEBUG: OnKey: guide (0xe0) pressed, action is Stop
>23:24:58  79.591393 T:139653791922240  NOTICE: CVideoPlayer::CloseFile()
>23:24:58  79.591408 T:139653791922240  NOTICE: VideoPlayer: waiting for threads to exit
>23:24:58  79.599884 T:139652119910144  NOTICE: CVideoPlayer::OnExit()
>23:24:58  79.599945 T:139652119910144  NOTICE: Closing stream player 1
>23:24:58  79.599968 T:139652119910144  NOTICE: Waiting for audio thread to exit
>23:24:58  79.600815 T:139652136695552  NOTICE: thread end: CVideoPlayerAudio::OnExit()
>23:24:58  79.600891 T:139652119910144  NOTICE: Closing audio device
>23:24:58  79.600945 T:139652136695552   DEBUG: Thread VideoPlayerAudio 139652136695552 terminating
>23:24:58  79.646416 T:139652119910144   DEBUG: CDVDAudio::Flush - flush audio stream
>23:24:58  79.646553 T:139653772986112   DEBUG: CActiveAE::DiscardStream - audio stream deleted
>23:24:58  79.646622 T:139653772986112   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
>23:24:58  79.647331 T:139652119910144   DEBUG: Previous line repeats 1 times.
>23:24:58  79.647392 T:139652119910144  NOTICE: Deleting audio codec
>23:24:58  79.647514 T:139652119910144  NOTICE: Closing stream player 2
>23:24:58  79.647530 T:139652119910144  NOTICE: waiting for video thread to exit
>23:24:58  79.655777 T:139653763618560    INFO: CActiveAESink::OpenSink - initialize sink
>23:24:58  79.691040 T:139653193651968  NOTICE: thread end: video_thread
>23:24:58  79.691093 T:139653193651968   DEBUG: Thread VideoPlayerVideo 139653193651968 terminating
>23:24:58  79.691269 T:139652119910144  NOTICE: deleting video codec
>23:24:58  79.691368 T:139652119910144   DEBUG: VAAPI::Release pre-cleanup
>23:24:58  79.693062 T:139652119910144   DEBUG: CSMBFile::Close closing fd 10000
>23:24:58  79.699806 T:139652119910144   DEBUG: OnPlayBackStopped: play state was 2, starting 0
>23:24:58  79.699905 T:139652119910144   DEBUG: Thread VideoPlayer 139652119910144 terminating
>23:24:58  79.699966 T:139653791922240  NOTICE: VideoPlayer: finished waiting
>23:24:59  79.700027 T:139653791922240   DEBUG: DeleteRenderer - deleting renderer
>23:24:59  79.700096 T:139653791922240  NOTICE: VAAPI::Close
>23:24:59  79.700699 T:139652128302848  NOTICE: COutput::OnExit: Output Thread terminated
>23:24:59  79.700775 T:139652128302848   DEBUG: Thread Vaapi-Output 139652128302848 terminating
>23:24:59  79.700844 T:139653791922240   DEBUG: VAAPI::FiniVAAPIOutput destroying 5 video surfaces
>23:24:59  79.703056 T:139653791922240  NOTICE: VAAPI::Close - closing decoder context
>23:24:59  79.704102 T:139653791913728   DEBUG: GetMovieId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMovie from movie where idFile=46
>23:24:59  79.704422 T:139653791913728   DEBUG: GetEpisodeId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idEpisode from episode where idFile=46
>23:24:59  79.704704 T:139653791913728   DEBUG: GetMusicVideoId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMVideo from musicvideo where idFile=46
>23:24:59  79.705788 T:139653791922240   DEBUG: LinuxRendererGL: Cleaning up GL resources
>23:24:59  79.705841 T:139653791922240   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
>23:24:59  79.705872 T:139653791922240   DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
>23:24:59  79.706024 T:139653791913728   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
>23:24:59  79.706078 T:139653791913728   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
>23:24:59  79.710487 T:139653791922240   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
>23:24:59  79.714607 T:139653791922240   DEBUG: ------ Window Init (MyVideoNav.xml) ------
>23:24:59  79.715317 T:139653791922240   DEBUG: CGUIMediaWindow::GetDirectory (smb://HJEMMENAS/Test/DTS/DTS-HD MA/)
>23:24:59  79.715347 T:139653791922240   DEBUG:   ParentPath = [smb://HJEMMENAS/Test/DTS/DTS-HD MA/]
>23:24:59  79.724846 T:139653791922240   DEBUG: RunQuery took 0 ms for 6 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=6
>23:24:59  79.725761 T:139652128302848   DEBUG: Thread BackgroundLoader start, auto delete: false
>23:24:59  79.729713 T:139653185259264   DEBUG: GetImageHash - unable to stat url
>23:24:59  79.738045 T:139653791922240  NOTICE: CVideoPlayer::CloseFile()
>23:24:59  79.738075 T:139653791922240  NOTICE: VideoPlayer: waiting for threads to exit
>23:24:59  79.738091 T:139653791922240  NOTICE: VideoPlayer: finished waiting
>23:24:59  79.738106 T:139653791922240  NOTICE: CVideoPlayer::CloseFile()
>23:24:59  79.738113 T:139653791922240  NOTICE: VideoPlayer: waiting for threads to exit
>23:24:59  79.738121 T:139653791922240  NOTICE: VideoPlayer: finished waiting
>23:24:59  79.739143 T:139653791922240   DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
>23:24:59  79.739578 T:139653202044672   DEBUG: DoWork - Saving file state for video item smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv
>23:24:59  79.749886 T:139653791913728   DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc
>23:24:59  79.749924 T:139653791913728   DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate
>23:24:59  79.760056 T:139653185259264   DEBUG: GetImageHash - unable to stat url
>23:24:59  79.847237 T:139653763618560   DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0
>23:24:59  79.847313 T:139653763618560    INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0"
>23:24:59  79.850136 T:139653763618560    INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"
>23:24:59  79.850342 T:139653763618560    INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
>23:24:59  79.850357 T:139653763618560    INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
>23:24:59  79.850357 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820
>23:24:59  79.850731 T:139652128302848   DEBUG: Thread BackgroundLoader 139652128302848 terminating
>23:24:59  79.856705 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820
>23:24:59  79.856743 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
>23:24:59  79.857964 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
>23:24:59  79.857994 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
>23:24:59  79.858009 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)
>23:24:59  79.858025 T:139653763618560   DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
>23:24:59  79.858032 T:139653763618560   DEBUG:   Output Device : HDA Intel PCH
>23:24:59  79.858047 T:139653763618560   DEBUG:   Sample Rate   : 44100
>23:24:59  79.858063 T:139653763618560   DEBUG:   Sample Format : AE_FMT_S32NE
>23:24:59  79.858086 T:139653763618560   DEBUG:   Channel Count : 2
>23:24:59  79.858101 T:139653763618560   DEBUG:   Channel Layout: FL,FR
>23:24:59  79.858116 T:139653763618560   DEBUG:   Frames        : 2205
>23:24:59  79.858124 T:139653763618560   DEBUG:   Frame Size    : 8
>23:24:59  79.860542 T:139653772986112   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
>23:24:59  80.128578 T:139653791922240   DEBUG: Previous line repeats 1 times.
>23:24:59  80.128639 T:139653791922240   DEBUG: LIRC: Update - NEW at 74412:80 0 KEY_STOP_UP devinput (KEY_STOP_UP)
>23:25:00  81.499626 T:139653791922240   DEBUG: LIRC: Update - NEW at 75783:ae 0 KEY_EXIT devinput (KEY_EXIT)
>23:25:00  81.499710 T:139653791922240   DEBUG: OnKey: menu (0xd8) pressed, action is Back
>23:25:00  81.500023 T:139653791922240   DEBUG: CGUIMediaWindow::GetDirectory (smb://HJEMMENAS/Test/DTS/)
>23:25:00  81.500061 T:139653791922240   DEBUG:   ParentPath = [smb://HJEMMENAS/Test/]
>23:25:00  81.505539 T:139653791922240   DEBUG: RunQuery took 0 ms for 1 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=4
>23:25:00  81.505768 T:139652128302848   DEBUG: Thread BackgroundLoader start, auto delete: false
>23:25:00  81.506584 T:139653185259264   DEBUG: GetImageHash - unable to stat url
>23:25:00  81.512596 T:139652128302848   DEBUG: Thread BackgroundLoader 139652128302848 terminating
>23:25:01  82.025574 T:139653791922240   DEBUG: LIRC: Update - NEW at 76309:ae 0 KEY_EXIT_UP devinput (KEY_EXIT_UP)
>23:25:02  83.017593 T:139653791922240   DEBUG: LIRC: Update - NEW at 77301:ae 0 KEY_EXIT devinput (KEY_EXIT)
>23:25:02  83.017677 T:139653791922240   DEBUG: OnKey: menu (0xd8) pressed, action is Back
>23:25:02  83.017937 T:139653791922240   DEBUG: CGUIMediaWindow::GetDirectory (smb://HJEMMENAS/Test/)
>23:25:02  83.017967 T:139653791922240   DEBUG:   ParentPath = [smb://HJEMMENAS/]
>23:25:02  83.036194 T:139653791922240   DEBUG: RunQuery took 0 ms for 8 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=1
>23:25:02  83.037468 T:139652128302848   DEBUG: Thread BackgroundLoader start, auto delete: false
>23:25:02  83.040840 T:139653185259264   DEBUG: GetImageHash - unable to stat url
>23:25:02  83.080589 T:139652128302848   DEBUG: Thread BackgroundLoader 139652128302848 terminating
>23:25:02  83.536102 T:139653791922240   DEBUG: LIRC: Update - NEW at 77820:ae 0 KEY_EXIT_UP devinput (KEY_EXIT_UP)
>23:25:03  84.424301 T:139653791922240   DEBUG: LIRC: Update - NEW at 78708:ae 0 KEY_EXIT devinput (KEY_EXIT)
>23:25:03  84.424393 T:139653791922240   DEBUG: OnKey: menu (0xd8) pressed, action is Back
>23:25:03  84.424522 T:139653791922240   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
>23:25:04  84.746628 T:139653791922240   DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
>23:25:04  84.747528 T:139653791922240   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
>23:25:04  84.747604 T:139653791922240   DEBUG: ------ Window Init (Home.xml) ------
>23:25:04  84.961571 T:139653791922240   DEBUG: LIRC: Update - NEW at 79245:ae 0 KEY_EXIT_UP devinput (KEY_EXIT_UP)
>23:25:05  85.888123 T:139653791922240   DEBUG: LIRC: Update - NEW at 80172:69 0 KEY_LEFT devinput (KEY_LEFT)
>23:25:05  85.888229 T:139653791922240   DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left
>23:25:05  86.367508 T:139653791922240   DEBUG: LIRC: Update - NEW at 80651:69 0 KEY_LEFT_UP devinput (KEY_LEFT_UP)
>23:25:05  86.372238 T:139653791922240   DEBUG: LIRC: Update - NEW at 80656:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:25:05  86.372314 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:25:06  86.758301 T:139653791922240   DEBUG: LIRC: Update - NEW at 81042:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:25:06  86.776237 T:139653791922240   DEBUG: LIRC: Update - NEW at 81060:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:25:06  86.776306 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:25:06  87.124336 T:139653791922240   DEBUG: LIRC: Update - NEW at 81408:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:25:06  87.141136 T:139653791922240   DEBUG: LIRC: Update - NEW at 81425:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:25:06  87.141174 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:25:06  87.674355 T:139653791922240   DEBUG: LIRC: Update - NEW at 81958:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:25:07  87.991653 T:139653791922240   DEBUG: LIRC: Update - NEW at 82275:6a 0 KEY_RIGHT devinput (KEY_RIGHT)
>23:25:07  87.991699 T:139653791922240   DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
>23:25:07  88.400772 T:139653791922240   DEBUG: LIRC: Update - NEW at 82684:6a 0 KEY_RIGHT_UP devinput (KEY_RIGHT_UP)
>23:25:07  88.441948 T:139653791922240   DEBUG: LIRC: Update - NEW at 82725:160 0 KEY_OK devinput (KEY_OK)
>23:25:07  88.442047 T:139653791922240   DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
>23:25:07  88.442177 T:139653791922240   DEBUG: Activating window ID: 10004
>23:25:08  88.759903 T:139653791922240   DEBUG: ------ Window Deinit (Home.xml) ------
>23:25:08  88.760468 T:139653791922240   DEBUG: ------ Window Init (Settings.xml) ------
>23:25:08  88.760506 T:139653791922240    INFO: Loading skin file: Settings.xml, load type: KEEP_IN_MEMORY
>23:25:08  88.910400 T:139653791922240   DEBUG: LIRC: Update - NEW at 83194:160 0 KEY_OK_UP devinput (KEY_OK_UP)
>23:25:09  89.894287 T:139653791922240   DEBUG: LIRC: Update - NEW at 84178:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:25:09  89.894371 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:25:09  90.291504 T:139653791922240   DEBUG: LIRC: Update - NEW at 84575:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:25:09  90.332466 T:139653791922240   DEBUG: LIRC: Update - NEW at 84616:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:25:09  90.332550 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:25:09  90.683937 T:139653791922240   DEBUG: LIRC: Update - NEW at 84967:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:25:10  90.724907 T:139653791922240   DEBUG: LIRC: Update - NEW at 85008:160 0 KEY_OK devinput (KEY_OK)
>23:25:10  90.724991 T:139653791922240   DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
>23:25:10  90.725090 T:139653791922240   DEBUG: Activating window ID: 10016
>23:25:10  91.043259 T:139653791922240   DEBUG: ------ Window Deinit (Settings.xml) ------
>23:25:10  91.043655 T:139653791922240   DEBUG: ------ Window Init (SettingsCategory.xml) ------
>23:25:10  91.043709 T:139653791922240    INFO: Loading skin file: SettingsCategory.xml, load type: KEEP_IN_MEMORY
>23:25:10  91.054848 T:139653791922240   ERROR: Window Translator: Can't find window picturessettings
>23:25:10  91.054955 T:139653791922240   ERROR: Window Translator: Can't find window programssettings
>23:25:10  91.055031 T:139653791922240   ERROR: Window Translator: Can't find window weathersettings
>23:25:10  91.055115 T:139653791922240   ERROR: Window Translator: Can't find window musicsettings
>23:25:10  91.055222 T:139653791922240   ERROR: Window Translator: Can't find window videossettings
>23:25:10  91.055336 T:139653791922240   ERROR: Window Translator: Can't find window appearancesettings
>23:25:10  91.627953 T:139653791922240   DEBUG: LIRC: Update - NEW at 85911:160 0 KEY_OK_UP devinput (KEY_OK_UP)
>23:25:11  92.625404 T:139653791922240   DEBUG: LIRC: Update - NEW at 86909:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:25:11  92.625488 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:25:12  93.178703 T:139653791922240   DEBUG: LIRC: Update - NEW at 87462:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:25:12  93.351028 T:139653791922240   DEBUG: LIRC: Update - NEW at 87634:6a 0 KEY_RIGHT devinput (KEY_RIGHT)
>23:25:12  93.351112 T:139653791922240   DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
>23:25:13  93.728119 T:139653791922240   DEBUG: LIRC: Update - NEW at 88012:6a 0 KEY_RIGHT_UP devinput (KEY_RIGHT_UP)
>23:25:13  93.744797 T:139653791922240   DEBUG: LIRC: Update - NEW at 88028:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:25:13  93.744904 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:25:13  94.244850 T:139653791922240   DEBUG: LIRC: Update - NEW at 88528:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:25:13  94.261635 T:139653791922240   DEBUG: LIRC: Update - NEW at 88545:160 0 KEY_OK devinput (KEY_OK)
>23:25:13  94.261742 T:139653791922240   DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
>23:25:13  94.261948 T:139653791922240   DEBUG: ------ Window Init (DialogSelect.xml) ------
>23:25:13  94.261978 T:139653791922240    INFO: Loading skin file: DialogSelect.xml, load type: KEEP_IN_MEMORY
>23:25:13  94.696701 T:139653791922240   DEBUG: LIRC: Update - NEW at 88980:160 0 KEY_OK_UP devinput (KEY_OK_UP)
>23:25:14  94.713089 T:139653791922240   DEBUG: LIRC: Update - NEW at 88997:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:25:14  94.713173 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:25:14  95.079491 T:139653791922240   DEBUG: LIRC: Update - NEW at 89363:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:25:14  95.096138 T:139653791922240   DEBUG: LIRC: Update - NEW at 89380:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:25:14  95.096230 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:25:14  95.462807 T:139653791922240   DEBUG: LIRC: Update - NEW at 89746:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:25:14  95.479370 T:139653791922240   DEBUG: LIRC: Update - NEW at 89763:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:25:14  95.479477 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:25:15  95.846001 T:139653791922240   DEBUG: LIRC: Update - NEW at 90129:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:25:15  95.862801 T:139653791922240   DEBUG: LIRC: Update - NEW at 90146:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:25:15  95.862892 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:25:15  96.246407 T:139653791922240   DEBUG: LIRC: Update - NEW at 90530:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:25:15  96.262306 T:139653791922240   DEBUG: LIRC: Update - NEW at 90546:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:25:15  96.262344 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:25:15  96.629486 T:139653791922240   DEBUG: LIRC: Update - NEW at 90913:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:25:15  96.645996 T:139653791922240   DEBUG: LIRC: Update - NEW at 90929:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:25:15  96.646103 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:25:16  97.012840 T:139653791922240   DEBUG: LIRC: Update - NEW at 91296:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:25:16  97.029427 T:139653791922240   DEBUG: LIRC: Update - NEW at 91313:6c 0 KEY_DOWN devinput (KEY_DOWN)
>23:25:16  97.029533 T:139653791922240   DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
>23:25:16  97.546051 T:139653791922240   DEBUG: LIRC: Update - NEW at 91829:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)
>23:25:17  97.746353 T:139653791922240   DEBUG: LIRC: Update - NEW at 92030:160 0 KEY_OK devinput (KEY_OK)
>23:25:17  97.746452 T:139653791922240   DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
>23:25:17  97.996368 T:139653791922240   DEBUG: ------ Window Deinit (DialogSelect.xml) ------
>23:25:17  98.211250 T:139653772986112   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
>23:25:17  98.294762 T:139653791922240   DEBUG: LIRC: Update - NEW at 92578:160 0 KEY_OK_UP devinput (KEY_OK_UP)
>23:25:22 103.045113 T:139653791922240   DEBUG: LIRC: Update - NEW at 97329:ae 0 KEY_EXIT devinput (KEY_EXIT)
>23:25:22 103.045250 T:139653791922240   DEBUG: OnKey: menu (0xd8) pressed, action is PreviousMenu
>23:25:22 103.050316 T:139653791922240   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
>23:25:22 103.362343 T:139653791922240   DEBUG: ------ Window Deinit (SettingsCategory.xml) ------
>23:25:22 103.363297 T:139653791922240   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
>23:25:22 103.363365 T:139653791922240   DEBUG: ------ Window Init (Settings.xml) ------
>23:25:22 103.593620 T:139653791922240   DEBUG: LIRC: Update - NEW at 97877:ae 0 KEY_EXIT_UP devinput (KEY_EXIT_UP)
>23:25:23 104.614372 T:139653791922240   DEBUG: LIRC: Update - NEW at 98898:ae 0 KEY_EXIT devinput (KEY_EXIT)
>23:25:23 104.614456 T:139653791922240   DEBUG: OnKey: menu (0xd8) pressed, action is PreviousMenu
>23:25:23 104.614494 T:139653791922240   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
>23:25:24 104.943237 T:139653791922240   DEBUG: ------ Window Deinit (Settings.xml) ------
>23:25:24 104.943581 T:139653791922240   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
>23:25:24 104.943626 T:139653791922240   DEBUG: ------ Window Init (Home.xml) ------
>23:25:24 105.143471 T:139653791922240   DEBUG: LIRC: Update - NEW at 99427:ae 0 KEY_EXIT_UP devinput (KEY_EXIT_UP)
>23:25:25 105.906281 T:139653791922240   DEBUG: LIRC: Update - NEW at 100190:67 0 KEY_UP devinput (KEY_UP)
>23:25:25 105.906364 T:139653791922240   DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up
>23:25:25 106.092796 T:139653791922240   DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/libsse4-x86_64-linux.so)
>23:25:25 106.300728 T:139653791922240   DEBUG: LIRC: Update - NEW at 100584:67 0 KEY_UP_UP devinput (KEY_UP_UP)
>23:25:25 106.342209 T:139653791922240   DEBUG: LIRC: Update - NEW at 100626:67 0 KEY_UP devinput (KEY_UP)
>23:25:25 106.342293 T:139653791922240   DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up
>23:25:25 106.659447 T:139653791922240   DEBUG: LIRC: Update - NEW at 100943:67 0 KEY_UP_UP devinput (KEY_UP_UP)
>23:25:25 106.694237 T:139653791922240   DEBUG: LIRC: Update - NEW at 100978:67 0 KEY_UP devinput (KEY_UP)
>23:25:25 106.694283 T:139653791922240   DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up
>23:25:26 107.160477 T:139653791922240   DEBUG: LIRC: Update - NEW at 101444:67 0 KEY_UP_UP devinput (KEY_UP_UP)
>23:25:26 107.177170 T:139653791922240   DEBUG: LIRC: Update - NEW at 101461:6a 0 KEY_RIGHT devinput (KEY_RIGHT)
>23:25:26 107.177261 T:139653791922240   DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
>23:25:27 107.710304 T:139653791922240   DEBUG: LIRC: Update - NEW at 101994:6a 0 KEY_RIGHT_UP devinput (KEY_RIGHT_UP)
>23:25:27 107.851173 T:139653791922240   DEBUG: LIRC: Update - NEW at 102135:160 0 KEY_OK devinput (KEY_OK)
>23:25:27 107.851257 T:139653791922240   DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
>23:25:27 107.851395 T:139653791922240   DEBUG: Activating window ID: 10025
>23:25:27 108.178108 T:139653791922240   DEBUG: ------ Window Deinit (Home.xml) ------
>23:25:27 108.180290 T:139653791922240   DEBUG: ------ Window Init (MyVideoNav.xml) ------
>23:25:27 108.180634 T:139653791922240   DEBUG: CGUIMediaWindow::GetDirectory (smb://HJEMMENAS/Test/)
>23:25:27 108.180641 T:139653791922240   DEBUG:   ParentPath = [smb://HJEMMENAS/Test/]
>23:25:27 108.189636 T:139653791922240   DEBUG: RunQuery took 0 ms for 8 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=1
>23:25:27 108.190239 T:139652128302848   DEBUG: Thread BackgroundLoader start, auto delete: false
>23:25:27 108.193939 T:139653202044672   DEBUG: GetImageHash - unable to stat url
>23:25:27 108.237068 T:139652128302848   DEBUG: Thread BackgroundLoader 139652128302848 terminating
>23:25:27 108.396439 T:139653791922240   DEBUG: LIRC: Update - NEW at 102680:160 0 KEY_OK_UP devinput (KEY_OK_UP)
>23:25:28 108.862129 T:139653791922240   DEBUG: LIRC: Update - NEW at 103146:160 0 KEY_OK devinput (KEY_OK)
>23:25:28 108.862244 T:139653791922240   DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
>23:25:28 108.862862 T:139653791922240   DEBUG: CGUIMediaWindow::GetDirectory (smb://HJEMMENAS/Test/DTS/)
>23:25:28 108.862907 T:139653791922240   DEBUG:   ParentPath = [smb://HJEMMENAS/Test/]
>23:25:28 108.870354 T:139653791922240   DEBUG: RunQuery took 0 ms for 1 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=4
>23:25:28 108.870987 T:139652128302848   DEBUG: Thread BackgroundLoader start, auto delete: false
>23:25:28 108.873619 T:139653202044672   DEBUG: GetImageHash - unable to stat url
>23:25:28 108.882042 T:139652128302848   DEBUG: Thread BackgroundLoader 139652128302848 terminating
>23:25:28 109.393913 T:139653791922240   DEBUG: LIRC: Update - NEW at 103677:160 0 KEY_OK_UP devinput (KEY_OK_UP)
>23:25:28 109.592209 T:139653791922240   DEBUG: LIRC: Update - NEW at 103876:160 0 KEY_OK devinput (KEY_OK)
>23:25:28 109.592293 T:139653791922240   DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
>23:25:28 109.592880 T:139653791922240   DEBUG: CGUIMediaWindow::GetDirectory (smb://HJEMMENAS/Test/DTS/DTS-HD MA/)
>23:25:28 109.592911 T:139653791922240   DEBUG:   ParentPath = [smb://HJEMMENAS/Test/DTS/]
>23:25:28 109.603584 T:139653791922240   DEBUG: RunQuery took 0 ms for 6 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=6
>23:25:28 109.604469 T:139652128302848   DEBUG: Thread BackgroundLoader start, auto delete: false
>23:25:28 109.607513 T:139653202044672   DEBUG: GetImageHash - unable to stat url
>23:25:28 109.617172 T:139652128302848   DEBUG: Thread BackgroundLoader 139652128302848 terminating
>23:25:29 110.143852 T:139653791922240   DEBUG: LIRC: Update - NEW at 104427:160 0 KEY_OK_UP devinput (KEY_OK_UP)
>23:25:29 110.411606 T:139653791922240   DEBUG: LIRC: Update - NEW at 104695:160 0 KEY_OK devinput (KEY_OK)
>23:25:29 110.411713 T:139653791922240   DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
>23:25:29 110.417694 T:139653791922240   DEBUG: OnPlayMedia smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv
>23:25:29 110.418137 T:139653791913728   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
>23:25:29 110.418236 T:139653791913728   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
>23:25:29 110.426331 T:139653791913728   DEBUG: GetMovieId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMovie from movie where idFile=46
>23:25:29 110.426552 T:139653791922240   DEBUG: Loading settings for smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv
>23:25:29 110.426910 T:139653791913728   DEBUG: GetEpisodeId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idEpisode from episode where idFile=46
>23:25:29 110.427353 T:139653791913728   DEBUG: GetMusicVideoId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMVideo from musicvideo where idFile=46
>23:25:29 110.429588 T:139653791913728   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
>23:25:29 110.429665 T:139653791913728   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
>23:25:29 110.433067 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers(smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv)
>23:25:29 110.433105 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
>23:25:29 110.433136 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
>23:25:29 110.433151 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
>23:25:29 110.433189 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
>23:25:29 110.433220 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
>23:25:29 110.433243 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
>23:25:29 110.433289 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
>23:25:29 110.433319 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
>23:25:29 110.433357 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
>23:25:29 110.433380 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
>23:25:29 110.433403 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
>23:25:29 110.433426 T:139653791922240   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
>23:25:29 110.433456 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
>23:25:29 110.433472 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
>23:25:29 110.433487 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
>23:25:29 110.433495 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
>23:25:29 110.433510 T:139653791922240   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
>23:25:29 110.434998 T:139653791922240   DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
>23:25:29 110.435043 T:139653791922240  NOTICE: VideoPlayer: Opening: smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv
>23:25:29 110.435059 T:139653791922240 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
>23:25:29 110.435127 T:139653791922240   DEBUG: LinuxRendererGL: Cleaning up GL resources
>23:25:29 110.435181 T:139653791922240   DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
>23:25:29 110.435326 T:139652128302848   DEBUG: Thread VideoPlayer start, auto delete: false
>23:25:29 110.435448 T:139652128302848  NOTICE: Creating InputStream
>23:25:29 110.438515 T:139652128302848   DEBUG: CSMBFile::Open - opened smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv, fd=10000
>23:25:29 110.438980 T:139652128302848   DEBUG: ScanForExternalSubtitles: Searching for subtitles...
>23:25:29 110.440063 T:139652128302848   DEBUG: ScanForExternalSubtitles: END (total time: 2 ms)
>23:25:29 110.440102 T:139652128302848  NOTICE: Creating Demuxer
>23:25:29 110.441841 T:139652128302848   DEBUG: Open - probing detected format [matroska,webm]
>23:25:29 110.441994 T:139652128302848   DEBUG: Open - avformat_find_stream_info starting
>23:25:29 110.453056 T:139652128302848   DEBUG: Open - av_find_stream_info finished
>23:25:29 110.453087 T:139652128302848    INFO: ffmpeg[7F034B7FE700]: Input #0, matroska,webm, smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mk':
>23:25:29 110.453094 T:139652128302848    INFO: ffmpeg[7F034B7FE700]:   Metadata:
>23:25:29 110.453102 T:139652128302848    INFO: ffmpeg[7F034B7FE700]:     encoder         : libebml v1.3.0 + libmatroska v1.4.1
>23:25:29 110.453110 T:139652128302848    INFO: ffmpeg[7F034B7FE700]:     creation_time   : 2015-04-06 12:06:06
>23:25:29 110.453117 T:139652128302848    INFO: ffmpeg[7F034B7FE700]:   Duration: 00:00:21.15, start: 0.000000, bitrate: 15012 kb/s
>23:25:29 110.453140 T:139652128302848    INFO: ffmpeg[7F034B7FE700]:     Stream #0:0(eng): Video: h264 (High), yuv420p, 1920x1080, SAR 1:1 DAR 16:9, 29.97 fps, 29.97 tbr, 1k tbn, 59.94 tbc (default)
>23:25:29 110.453156 T:139652128302848    INFO: ffmpeg[7F034B7FE700]:     Stream #0:1(eng): Audio: dts (DTS-HD MA), 96000 Hz, 7.1, s32p (24 bit) (default)
>23:25:29 110.453163 T:139652128302848   DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0
>23:25:29 110.453171 T:139652128302848   DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1
>23:25:29 110.453201 T:139652128302848  NOTICE: Opening stream: 0 source: 256
>23:25:29 110.453217 T:139652128302848  NOTICE: Creating video codec with codec id: 28
>23:25:29 110.453224 T:139652128302848   DEBUG: FactoryCodec - Video:  - Opening
>23:25:29 110.453224 T:139652128302848  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
>23:25:29 110.453278 T:139652128302848   DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
>23:25:29 110.453285 T:139652128302848   DEBUG: FactoryCodec - Video: ff-h264 - Opened
>23:25:29 110.453293 T:139652128302848   DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28
>23:25:29 110.453293 T:139652128302848  NOTICE: Creating video thread
>23:25:29 110.453331 T:139652128302848  NOTICE: Opening stream: 1 source: 256
>23:25:29 110.453339 T:139652128302848  NOTICE: Finding audio codec for: 86020
>23:25:29 110.453346 T:139652128302848   DEBUG: FactoryCodec - Audio: passthrough - Opening
>23:25:29 110.453354 T:139652128302848   DEBUG: FactoryCodec - Audio: passthrough - Failed
>23:25:29 110.453354 T:139652128302848   DEBUG: FactoryCodec - Audio: FFmpeg - Opening
>23:25:29 110.453384 T:139652119910144   DEBUG: Thread VideoPlayerVideo start, auto delete: false
>23:25:29 110.453400 T:139652119910144  NOTICE: running thread: video_thread
>23:25:29 110.453407 T:139652128302848   DEBUG: FactoryCodec - Audio: FFmpeg - Opened
>23:25:29 110.453415 T:139652128302848  NOTICE: Creating audio thread
>23:25:29 110.453461 T:139652128302848   DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv
>23:25:29 110.453476 T:139653193651968   DEBUG: Thread VideoPlayerAudio start, auto delete: false
>23:25:29 110.453491 T:139653193651968  NOTICE: running thread: CVideoPlayerAudio::Process()
>23:25:29 110.453560 T:139652128302848   DEBUG: OnPlayBackStarted: play state was 1, starting 1
>23:25:29 110.453568 T:139652128302848   DEBUG: CVideoPlayer::SetCaching - caching state 2
>23:25:29 110.453636 T:139653791922240   DEBUG: PlayFile: OpenFile succeed, play state 2
>23:25:29 110.453690 T:139653193651968   DEBUG: CDVDAudio::Pause - pausing audio stream
>23:25:29 110.453796 T:139653791922240   DEBUG: OnPlayBackStarted: play state was 2, starting 0
>23:25:29 110.453896 T:139652119910144    INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
>23:25:29 110.453911 T:139652119910144   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
>23:25:29 110.454170 T:139652128302848  NOTICE: CDVDMessageQueue::GetLevel() - can't determine level
>23:25:29 110.454178 T:139652119910144   DEBUG: VAAPI - open decoder
>23:25:29 110.454193 T:139652128302848  NOTICE: CDVDMessageQueue::GetLevel() - can't determine level
>23:25:29 110.454338 T:139653193651968  NOTICE: Previous line repeats 25 times.
>23:25:29 110.454346 T:139653193651968  NOTICE: Creating audio stream (codec id: 86020, channels: 8, sample rate: 96000, no pass-through)
>23:25:29 110.454422 T:139653193651968   DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback
>23:25:29 110.455177 T:139652119910144   DEBUG: VAAPI - initialize version 0.39
>23:25:29 110.455193 T:139652119910144   DEBUG: VAAPI - driver in use: Intel i965 driver for Intel(R) Skylake - 1.7.0
>23:25:29 110.455193 T:139652119910144   DEBUG: VAAPI - attrib 0 (get/set) min -100 max 100 value 0x0
>23:25:29 110.455215 T:139652119910144   DEBUG: VAAPI - attrib 1 (get/set) min 0 max 100 value 0x32
>23:25:29 110.455223 T:139652119910144   DEBUG: VAAPI - attrib 2 (get/set) min -180 max 180 value 0x0
>23:25:29 110.455223 T:139652119910144   DEBUG: VAAPI - attrib 3 (get/set) min 0 max 100 value 0x32
>23:25:29 110.455231 T:139652119910144   DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0
>23:25:29 110.455231 T:139652119910144   DEBUG: VAAPI - profile 0
>23:25:29 110.455238 T:139652119910144   DEBUG: VAAPI - profile 1
>23:25:29 110.455238 T:139652119910144   DEBUG: VAAPI - profile 13
>23:25:29 110.455238 T:139652119910144   DEBUG: VAAPI - profile 6
>23:25:29 110.455246 T:139652119910144   DEBUG: VAAPI - profile 7
>23:25:29 110.455246 T:139652119910144   DEBUG: VAAPI - profile 15
>23:25:29 110.455261 T:139652119910144   DEBUG: VAAPI - profile 16
>23:25:29 110.455269 T:139652119910144   DEBUG: VAAPI - profile 8
>23:25:29 110.455269 T:139652119910144   DEBUG: VAAPI - profile 9
>23:25:29 110.455269 T:139652119910144   DEBUG: VAAPI - profile 10
>23:25:29 110.455276 T:139652119910144   DEBUG: VAAPI - profile -1
>23:25:29 110.455276 T:139652119910144   DEBUG: VAAPI - profile 12
>23:25:29 110.455284 T:139652119910144   DEBUG: VAAPI - profile 14
>23:25:29 110.455284 T:139652119910144   DEBUG: VAAPI - profile 17
>23:25:29 110.455467 T:139652136695552   DEBUG: Thread Vaapi-Output start, auto delete: false
>23:25:29 110.455475 T:139652136695552  NOTICE: COutput::OnStartup: Output Thread created
>23:25:29 110.456818 T:139652136695552   DEBUG: VAAPI::COutput::CreateEGLContext - created context
>23:25:29 110.456848 T:139652136695552   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libsse4-x86_64-linux.so)
>23:25:29 110.456863 T:139652136695552   DEBUG: Loading: /usr/lib/kodi/system/libsse4-x86_64-linux.so
>23:25:29 110.460136 T:139652136695552   DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created
>23:25:29 110.460228 T:139652119910144   DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vaapi
>23:25:29 110.460602 T:139653763618560    INFO: CActiveAESink::OpenSink - initialize sink
>23:25:29 110.461967 T:139652119910144   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
>23:25:29 110.463387 T:139653202044672   DEBUG: Previous line repeats 1 times.
>23:25:29 110.463417 T:139653202044672   DEBUG: CGUIInfoManager::SetCurrentMovie(smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv)
>23:25:29 110.463760 T:139652119910144   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
>23:25:29 110.466110 T:139653202044672   DEBUG: Previous line repeats 1 times.
>23:25:29 110.466133 T:139653202044672   DEBUG: GetMovieId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMovie from movie where idFile=46
>23:25:29 110.466286 T:139653202044672   DEBUG: Previous line repeats 1 times.
>23:25:29 110.466301 T:139653202044672   DEBUG: GetEpisodeId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idEpisode from episode where idFile=46
>23:25:29 110.466438 T:139653202044672   DEBUG: Previous line repeats 1 times.
>23:25:29 110.466446 T:139653202044672   DEBUG: GetMusicVideoId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMVideo from musicvideo where idFile=46
>23:25:29 110.467278 T:139653791913728   DEBUG: Previous line repeats 1 times.
>23:25:29 110.467293 T:139653791913728   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
>23:25:29 110.467323 T:139653791913728   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
>23:25:29 110.471962 T:139652119910144   DEBUG: CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 29.97. format: VAAPI
>23:25:29 110.476189 T:139653791922240   DEBUG: DeleteRenderer - deleting renderer
>23:25:29 110.476227 T:139653791922240   DEBUG: LinuxRendererGL: Cleaning up GL resources
>23:25:29 110.482956 T:139653791922240   DEBUG: Previous line repeats 1 times.
>23:25:29 110.482971 T:139653791922240   DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
>23:25:29 110.483002 T:139653791922240   DEBUG: GL: Requested render method: 0
>23:25:29 110.483047 T:139653791922240   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
>23:25:29 110.483101 T:139653791922240  NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
>23:25:29 110.483757 T:139653791922240   DEBUG: GL: Vertex Shader compilation log:
>23:25:29 110.483772 T:139653791922240   DEBUG: GL: Vertex Shader compiled successfully
>23:25:29 110.484818 T:139653791922240   DEBUG: GL: Pixel Shader compilation log:
>23:25:29 110.484833 T:139653791922240   DEBUG: GL: Fragment Shader compiled successfully
>23:25:29 110.493759 T:139653791922240   DEBUG: GL: Vertex Shader compilation log:
>23:25:29 110.493782 T:139653791922240   DEBUG: GL: Vertex Shader compiled successfully
>23:25:29 110.494102 T:139653791922240   DEBUG: GL: Pixel Shader compilation log:
>23:25:29 110.494118 T:139653791922240   DEBUG: GL: Fragment Shader compiled successfully
>23:25:29 110.497116 T:139653791922240  NOTICE: GL: NPOT texture support detected
>23:25:29 110.497124 T:139653791922240  NOTICE: GL: Using GL_ARB_pixel_buffer_object
>23:25:29 110.497131 T:139653791922240  NOTICE: Using GL_TEXTURE_2D
>23:25:29 110.497139 T:139653791922240   DEBUG: CRenderManager::Configure - 4
>23:25:29 110.497246 T:139653791922240   DEBUG: GL: Requested render method: 0
>23:25:29 110.497299 T:139653791922240   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
>23:25:29 110.497330 T:139653791922240  NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
>23:25:29 110.497803 T:139652128302848   DEBUG: CVideoPlayer::HandleMessages - player started 2
>23:25:29 110.497879 T:139653791922240   DEBUG: GL: Vertex Shader compilation log:
>23:25:29 110.497887 T:139653791922240   DEBUG: GL: Vertex Shader compiled successfully
>23:25:29 110.498871 T:139653791922240   DEBUG: GL: Pixel Shader compilation log:
>23:25:29 110.498886 T:139653791922240   DEBUG: GL: Fragment Shader compiled successfully
>23:25:29 110.507149 T:139653791922240  NOTICE: GL: NPOT texture support detected
>23:25:29 110.507164 T:139653791922240  NOTICE: GL: Using GL_ARB_pixel_buffer_object
>23:25:29 110.509338 T:139653791922240   DEBUG: Activating window ID: 12005
>23:25:29 110.509850 T:139653791922240   DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
>23:25:29 110.510284 T:139653791922240   DEBUG: ------ Window Init (VideoFullScreen.xml) ------
>23:25:29 110.662216 T:139653763618560   DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0
>23:25:29 110.662285 T:139653763618560    INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0"
>23:25:29 110.667511 T:139653763618560    INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x0a"
>23:25:29 110.668251 T:139653763618560    INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
>23:25:29 110.668289 T:139653763618560    INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
>23:25:29 110.668304 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 4800, bufferSize 19200
>23:25:29 110.673363 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 4800, bufferSize 19200
>23:25:29 110.673393 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
>23:25:29 110.675476 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 6 Output Channel Count: 6
>23:25:29 110.675514 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR,FC,LFE,BL,BR
>23:25:29 110.675537 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR,FC,LFE,BL,BR (ALSA: FL FR FC LFE RL RR)
>23:25:29 110.675552 T:139653763618560   DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
>23:25:29 110.675560 T:139653763618560   DEBUG:   Output Device : HDA Intel PCH
>23:25:29 110.675575 T:139653763618560   DEBUG:   Sample Rate   : 96000
>23:25:29 110.675583 T:139653763618560   DEBUG:   Sample Format : AE_FMT_S32NE
>23:25:29 110.675598 T:139653763618560   DEBUG:   Channel Count : 6
>23:25:29 110.675613 T:139653763618560   DEBUG:   Channel Layout: FL,FR,FC,LFE,BL,BR
>23:25:29 110.675621 T:139653763618560   DEBUG:   Frames        : 4800
>23:25:29 110.675636 T:139653763618560   DEBUG:   Frame Size    : 24
>23:25:29 110.693665 T:139653772986112   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
>23:25:30 110.702087 T:139652128302848   DEBUG: Previous line repeats 1 times.
>23:25:30 110.702141 T:139652128302848   DEBUG: CVideoPlayer::HandleMessages - player started 1
>23:25:30 110.702164 T:139652128302848   DEBUG: CVideoPlayer::SetCaching - caching state 3
>23:25:30 110.702202 T:139652128302848   DEBUG: CVideoPlayer::SetCaching - caching state 0
>23:25:30 110.702225 T:139652128302848   DEBUG: VideoPlayer::Sync - Audio - pts: 106000.000000, cache: 307217.273115, totalcache: 600000.023842
>23:25:30 110.702240 T:139652128302848   DEBUG: VideoPlayer::Sync - Video - pts: 0.000000, cache: 50000.000000, totalcache: 100000.000000
>23:25:30 110.702286 T:139653193651968   DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-201217.273115)
>23:25:30 110.702385 T:139652119910144   DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-201217.273115)
>23:25:30 110.702499 T:139653193651968   DEBUG: CDVDAudio::Resume - resume audio stream
>23:25:30 110.702606 T:139652119910144   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
>23:25:30 110.703552 T:139653772986112   DEBUG: ActiveAE - start sync of audio stream
>23:25:30 110.704750 T:139652119910144   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
>23:25:30 110.839127 T:139653772986112   DEBUG: Previous line repeats 1 times.
>23:25:30 110.839188 T:139653772986112   DEBUG: ActiveAE::SyncStream - average error of -6.955264, start adjusting
>23:25:30 110.839226 T:139653772986112   DEBUG: ActiveAE::SyncStream - average error -0.955264 below threshold of 30.000000
>23:25:30 110.957832 T:139653791922240   DEBUG: LIRC: Update - NEW at 105241:160 0 KEY_OK_UP devinput (KEY_OK_UP)
>23:25:31 111.892036 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-29904.768901, adjusted:-29904.768901
>23:25:32 112.891632 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-1644.172062, adjusted:-1644.172062
>23:25:33 113.891884 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:1565.117492, adjusted:1565.117492
>23:25:34 114.792549 T:139652119910144   DEBUG: CPullupCorrection: detected pattern of length 1: 33366.67, frameduration: 33366.666667
>23:25:34 114.892418 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:158.480861, adjusted:158.480861
>23:25:35 115.891525 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-117.996698, adjusted:-117.996698
>23:25:36 116.891312 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:40.000305, adjusted:40.000305
>23:25:37 117.892754 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:58.657367, adjusted:58.657367
>23:25:38 118.891678 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-199.101747, adjusted:-199.101747
>23:25:39 119.809769 T:139652119910144   DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 1, Bufferlevel: 3, dropped: 1
>23:25:39 119.892059 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:89.010628, adjusted:89.010628
>23:25:39 120.178154 T:139652119910144   DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 2, Bufferlevel: 3, dropped: 1
>23:25:39 120.578339 T:139652119910144   DEBUG: Previous line repeats 2 times.
>23:25:39 120.578384 T:139652119910144   DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 3, Bufferlevel: 3, dropped: 1
>23:25:40 120.891991 T:139653193651968   DEBUG: Previous line repeats 1 times.
>23:25:40 120.892036 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-81.392226, adjusted:-81.392226
>23:25:40 121.078049 T:139652119910144   DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 4, Bufferlevel: 3, dropped: 1
>23:25:41 121.892014 T:139653193651968   DEBUG: Previous line repeats 3 times.
>23:25:41 121.892075 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:94.197064, adjusted:94.197064
>23:25:42 122.891800 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:106.256717, adjusted:106.256717
>23:25:43 123.891754 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-51.715567, adjusted:-51.715567
>23:25:44 124.891586 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-47.256467, adjusted:-47.256467
>23:25:44 125.544952 T:139652119910144   DEBUG: CPullupCorrection: detected pattern of length 1: 33366.67, frameduration: 33366.666667
>23:25:45 125.991837 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:93.143874, adjusted:93.143874
>23:25:46 126.991783 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-287.026813, adjusted:-287.026813
>23:25:47 127.991585 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:186.795732, adjusted:186.795732
>23:25:48 128.990601 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-26.894973, adjusted:-26.894973
>23:25:49 129.942200 T:139652119910144    INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 29.970030 fps
>23:25:49 129.942261 T:139652119910144   DEBUG: CPullupCorrection: pattern lost on diff 166833.333333, number of losses 1
>23:25:49 129.990631 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:86.684039, adjusted:86.684039
>23:25:50 130.990692 T:139653193651968   DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-44.721926, adjusted:-44.721926
>23:25:50 131.489639 T:139653193651968  NOTICE: CDVDMessageQueue::GetLevel() - can't determine level
>23:25:50 131.491608 T:139653193651968  NOTICE: Previous line repeats 2 times.
>23:25:50 131.491638 T:139653193651968   DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_EOF
>23:25:50 131.491653 T:139653193651968  NOTICE: CVideoPlayerAudio::Process - stream stalled
>23:25:50 131.512726 T:139652128302848  NOTICE: CVideoPlayer::OnExit()
>23:25:50 131.512817 T:139652128302848  NOTICE: VideoPlayer: eof, waiting for queues to empty
>23:25:50 131.512833 T:139652128302848  NOTICE: Closing stream player 1
>23:25:50 131.512848 T:139652128302848  NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty
>23:25:50 131.513077 T:139653193651968   DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
>23:25:50 131.513199 T:139652128302848  NOTICE: Waiting for audio thread to exit
>23:25:50 131.513260 T:139653193651968   ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
>23:25:50 131.513275 T:139653193651968  NOTICE: thread end: CVideoPlayerAudio::OnExit()
>23:25:50 131.513351 T:139652128302848  NOTICE: Closing audio device
>23:25:50 131.513489 T:139653193651968   DEBUG: Thread VideoPlayerAudio 139653193651968 terminating
>23:25:51 131.738998 T:139653772986112   DEBUG: CActiveAE::DiscardStream - audio stream deleted
>23:25:51 131.739105 T:139653772986112   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
>23:25:51 131.739334 T:139652128302848   DEBUG: Previous line repeats 1 times.
>23:25:51 131.739380 T:139652128302848  NOTICE: Deleting audio codec
>23:25:51 131.739426 T:139652128302848  NOTICE: Closing stream player 2
>23:25:51 131.739471 T:139652128302848  NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty
>23:25:51 131.739777 T:139652119910144   DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
>23:25:51 131.739914 T:139652128302848  NOTICE: waiting for video thread to exit
>23:25:51 131.739960 T:139652119910144   ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
>23:25:51 131.740005 T:139652119910144  NOTICE: thread end: video_thread
>23:25:51 131.740051 T:139652119910144   DEBUG: Thread VideoPlayerVideo 139652119910144 terminating
>23:25:51 131.740112 T:139652128302848  NOTICE: deleting video codec
>23:25:51 131.740326 T:139652128302848   DEBUG: VAAPI::Release pre-cleanup
>23:25:51 131.746948 T:139652128302848   DEBUG: CSMBFile::Close closing fd 10000
>23:25:51 131.747299 T:139652128302848   DEBUG: OnPlayBackEnded: play state was 2, starting 0
>23:25:51 131.747330 T:139652128302848   DEBUG: Thread VideoPlayer 139652128302848 terminating
>23:25:51 131.747589 T:139653791922240   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
>23:25:51 131.747604 T:139653791922240   DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
>23:25:51 131.749222 T:139653791913728   DEBUG: GetMovieId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMovie from movie where idFile=46
>23:25:51 131.749359 T:139653791913728   DEBUG: GetEpisodeId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idEpisode from episode where idFile=46
>23:25:51 131.749481 T:139653791913728   DEBUG: GetMusicVideoId (smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv), query = select idMVideo from musicvideo where idFile=46
>23:25:51 131.749878 T:139653791922240   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
>23:25:51 131.750122 T:139653791913728   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
>23:25:51 131.750153 T:139653791913728   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
>23:25:51 131.751175 T:139653791922240   DEBUG: ------ Window Init (MyVideoNav.xml) ------
>23:25:51 131.751602 T:139653791922240   DEBUG: CGUIMediaWindow::GetDirectory (smb://HJEMMENAS/Test/DTS/DTS-HD MA/)
>23:25:51 131.751602 T:139653791922240   DEBUG:   ParentPath = [smb://HJEMMENAS/Test/DTS/DTS-HD MA/]
>23:25:51 131.757050 T:139653791922240   DEBUG: RunQuery took 1 ms for 6 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=6
>23:25:51 131.757477 T:139652128302848   DEBUG: Thread BackgroundLoader start, auto delete: false
>23:25:51 131.767227 T:139652128302848   DEBUG: Thread BackgroundLoader 139652128302848 terminating
>23:25:51 131.775055 T:139653185259264   DEBUG: GetImageHash - unable to stat url
>23:25:51 131.781418 T:139653791922240  NOTICE: CVideoPlayer::CloseFile()
>23:25:51 131.781464 T:139653791922240  NOTICE: VideoPlayer: waiting for threads to exit
>23:25:51 131.781479 T:139653791922240  NOTICE: VideoPlayer: finished waiting
>23:25:51 131.781479 T:139653791922240   DEBUG: DeleteRenderer - deleting renderer
>23:25:51 131.781494 T:139653791922240  NOTICE: VAAPI::Close
>23:25:51 131.783569 T:139652136695552  NOTICE: COutput::OnExit: Output Thread terminated
>23:25:51 131.783600 T:139652136695552   DEBUG: Thread Vaapi-Output 139652136695552 terminating
>23:25:51 131.783905 T:139653791922240   DEBUG: VAAPI::FiniVAAPIOutput destroying 1 video surfaces
>23:25:51 131.784149 T:139653791922240  NOTICE: VAAPI::Close - closing decoder context
>23:25:51 131.785141 T:139653791922240   DEBUG: LinuxRendererGL: Cleaning up GL resources
>23:25:51 131.785156 T:139653791922240  NOTICE: CVideoPlayer::CloseFile()
>23:25:51 131.785172 T:139653791922240  NOTICE: VideoPlayer: waiting for threads to exit
>23:25:51 131.785172 T:139653791922240  NOTICE: VideoPlayer: finished waiting
>23:25:51 131.785706 T:139653791922240   DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
>23:25:51 131.845703 T:139653202044672   DEBUG: DoWork - Saving file state for video item smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv
>23:25:51 131.850296 T:139653202044672   DEBUG: DoWork - Marking video item smb://HJEMMENAS/Test/DTS/DTS-HD MA/dts_sfx_long_lossless_1080p-thedigitaltheater.mkv as watched
>23:25:51 131.857697 T:139653791913728   DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc
>23:25:51 131.857773 T:139653791913728   DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate
>23:25:51 131.883301 T:139653185259264   DEBUG: GetImageHash - unable to stat url
>23:25:51 132.089127 T:139653763618560    INFO: CActiveAESink::OpenSink - initialize sink
>23:25:51 132.290237 T:139653763618560   DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0
>23:25:51 132.290314 T:139653763618560    INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0"
>23:25:51 132.294479 T:139653763618560    INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"
>23:25:51 132.294693 T:139653763618560    INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
>23:25:51 132.294693 T:139653763618560    INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
>23:25:51 132.294708 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820
>23:25:51 132.300018 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820
>23:25:51 132.300049 T:139653763618560   DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
>23:25:51 132.301086 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
>23:25:51 132.301102 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
>23:25:51 132.301102 T:139653763618560   DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)
>23:25:51 132.301102 T:139653763618560   DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
>23:25:51 132.301102 T:139653763618560   DEBUG:   Output Device : HDA Intel PCH
>23:25:51 132.301117 T:139653763618560   DEBUG:   Sample Rate   : 44100
>23:25:51 132.301117 T:139653763618560   DEBUG:   Sample Format : AE_FMT_S32NE
>23:25:51 132.301117 T:139653763618560   DEBUG:   Channel Count : 2
>23:25:51 132.301132 T:139653763618560   DEBUG:   Channel Layout: FL,FR
>23:25:51 132.301132 T:139653763618560   DEBUG:   Frames        : 2205
>23:25:51 132.301132 T:139653763618560   DEBUG:   Frame Size    : 8
>23:25:51 132.302002 T:139653772986112   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
>23:26:00 140.794464 T:139653791922240   DEBUG: Previous line repeats 1 times.
>23:26:00 140.794540 T:139653791922240   DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/libsse4-x86_64-linux.so)
>23:26:21 161.883484 T:139653202044672   DEBUG: Thread JobWorker 139653202044672 terminating (autodelete)
>23:26:21 161.883560 T:139653185259264   DEBUG: Thread JobWorker 139653185259264 terminating (autodelete)

Actions: View

Attachments on bug 96382: 124321 | 124322 | 128086

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