· 6 years ago · Dec 06, 2019, 09:12 AM
12019-12-06 08:55:11.169 T:2973264992 NOTICE: -----------------------------------------------------------------------
22019-12-06 08:55:11.169 T:2973264992 NOTICE: Starting Kodi (18.4 Git:20191027-06c77692ee). Platform: Linux ARM 32-bit
32019-12-06 08:55:11.169 T:2973264992 NOTICE: Using Release Kodi x32 build
42019-12-06 08:55:11.169 T:2973264992 NOTICE: Kodi compiled 2019-10-27 by GCC 8.3.0 for Linux ARM 32-bit version 4.18.20 (266772)
52019-12-06 08:55:11.169 T:2973264992 NOTICE: Running on Raspbian GNU/Linux 10 (buster), kernel: Linux ARM 32-bit version 4.19.86-v7l+
62019-12-06 08:55:11.171 T:2973264992 NOTICE: FFmpeg version/source: 4.0.4-Kodi
72019-12-06 08:55:11.171 T:2973264992 NOTICE: Host CPU: ARMv7 Processor rev 3 (v7l), 4 cores available
82019-12-06 08:55:11.171 T:2973264992 NOTICE: ARM Features: Neon enabled
92019-12-06 08:55:11.171 T:2973264992 NOTICE: special://xbmc/ is mapped to: /usr/share/kodi
102019-12-06 08:55:11.171 T:2973264992 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/arm-linux-gnueabihf/kodi
112019-12-06 08:55:11.171 T:2973264992 NOTICE: special://xbmcbinaddons/ is mapped to: /usr/lib/arm-linux-gnueabihf/kodi/addons
122019-12-06 08:55:11.171 T:2973264992 NOTICE: special://masterprofile/ is mapped to: /home/pi/.kodi/userdata
132019-12-06 08:55:11.171 T:2973264992 NOTICE: special://envhome/ is mapped to: /home/pi
142019-12-06 08:55:11.171 T:2973264992 NOTICE: special://home/ is mapped to: /home/pi/.kodi
152019-12-06 08:55:11.171 T:2973264992 NOTICE: special://temp/ is mapped to: /home/pi/.kodi/temp
162019-12-06 08:55:11.171 T:2973264992 NOTICE: special://logpath/ is mapped to: /home/pi/.kodi/temp
172019-12-06 08:55:11.171 T:2973264992 NOTICE: The executable running is: /usr/lib/arm-linux-gnueabihf/kodi/kodi.bin_v8
182019-12-06 08:55:11.172 T:2973264992 NOTICE: Local hostname: raspberrypi
192019-12-06 08:55:11.172 T:2973264992 NOTICE: Log File is located: /home/pi/.kodi/temp/kodi.log
202019-12-06 08:55:11.172 T:2973264992 NOTICE: -----------------------------------------------------------------------
212019-12-06 08:55:11.174 T:2973264992 INFO: loading settings
222019-12-06 08:55:11.176 T:2973264992 NOTICE: special://profile/ is mapped to: special://masterprofile/
232019-12-06 08:55:11.192 T:2973264992 DEBUG: CSkinSettings: no <skinsettings> tag found
242019-12-06 08:55:11.193 T:2973264992 NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml
252019-12-06 08:55:11.194 T:2973264992 NOTICE: Contents of special://xbmc/system/advancedsettings.xml are...
26 <?xml version="1.0" encoding="UTF-8" ?>
27 <advancedsettings>
28 <fanartres>720</fanartres>
29 <imageres>540</imageres>
30 <video>
31 <busydialogdelayms>750</busydialogdelayms>
32 </video>
33 <samba>
34 <clienttimeout>30</clienttimeout>
35 </samba>
36 <cache>
37 <memorysize>31457280</memorysize>
38 </cache>
39 </advancedsettings>
402019-12-06 08:55:11.194 T:2973264992 WARNING: CSettingsManager: missing version attribute
412019-12-06 08:55:11.195 T:2973264992 NOTICE: Loaded settings file from special://profile/advancedsettings.xml
422019-12-06 08:55:11.196 T:2973264992 NOTICE: Contents of special://profile/advancedsettings.xml are...
43 <?xml version="1.0" encoding="utf-8" ?>
44 <advancedsettings>
45 <videolibrary>
46 <cleanonupdate>true</cleanonupdate>
47 <usefasthash>true</usefasthash>
48 <recentlyaddeditems>35</recentlyaddeditems>
49 <importwatchedstate>true</importwatchedstate>
50 <importresumepoint>true</importresumepoint>
51 </videolibrary>
52 <videodatabase>
53 <type>mysql</type>
54 <host>192.168.1.16</host>
55 <port>3306</port>
56 <user>kodi</user>
57 <pass>*****</pass>
58 </videodatabase>
59 </advancedsettings>
602019-12-06 08:55:11.196 T:2973264992 WARNING: VIDEO database configuration is experimental.
612019-12-06 08:55:11.196 T:2973264992 WARNING: CSettingsManager: missing version attribute
622019-12-06 08:55:11.197 T:2973264992 NOTICE: Default Video Player: VideoPlayer
632019-12-06 08:55:11.197 T:2973264992 NOTICE: Default Audio Player: paplayer
642019-12-06 08:55:11.197 T:2973264992 NOTICE: Enabled debug logging due to GUI setting (2)
652019-12-06 08:55:11.197 T:2973264992 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM"
662019-12-06 08:55:11.197 T:2973264992 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
672019-12-06 08:55:11.202 T:2973264992 INFO: creating subdirectories
682019-12-06 08:55:11.202 T:2973264992 INFO: userdata folder: special://masterprofile/
692019-12-06 08:55:11.202 T:2973264992 INFO: recording folder:
702019-12-06 08:55:11.202 T:2973264992 INFO: screenshots folder:
712019-12-06 08:55:11.354 T:2973264992 NOTICE: PulseAudio: Server not running
722019-12-06 08:55:11.354 T:2963271888 DEBUG: Thread Lirc start, auto delete: false
732019-12-06 08:55:11.354 T:2963271888 INFO: Loading special://xbmc/system/Lircmap.xml
742019-12-06 08:55:11.354 T:2952786128 DEBUG: Thread libinput start, auto delete: false
752019-12-06 08:55:11.357 T:2963271888 INFO: * Adding remote mapping for device 'mceusb'
762019-12-06 08:55:11.357 T:2963271888 INFO: * Adding remote mapping for device 'XboxDVDDongle'
772019-12-06 08:55:11.357 T:2963271888 INFO: * Adding remote mapping for device 'Microsoft_Xbox'
782019-12-06 08:55:11.357 T:2963271888 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
792019-12-06 08:55:11.358 T:2963271888 INFO: * Adding remote mapping for device 'anysee'
802019-12-06 08:55:11.358 T:2963271888 INFO: * Adding remote mapping for device 'iMON-PAD'
812019-12-06 08:55:11.358 T:2963271888 INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
822019-12-06 08:55:11.358 T:2963271888 INFO: * Adding remote mapping for device 'MCE_via_iMON'
832019-12-06 08:55:11.358 T:2963271888 INFO: * Adding remote mapping for device 'TwinHanRemote'
842019-12-06 08:55:11.358 T:2963271888 INFO: * Adding remote mapping for device 'linux-input-layer'
852019-12-06 08:55:11.358 T:2963271888 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
862019-12-06 08:55:11.358 T:2963271888 INFO: * Adding remote mapping for device 'mediacenter'
872019-12-06 08:55:11.358 T:2963271888 INFO: * Adding remote mapping for device 'devinput'
882019-12-06 08:55:11.358 T:2963271888 INFO: * Adding remote mapping for device 'devinput-32'
892019-12-06 08:55:11.358 T:2963271888 INFO: * Adding remote mapping for device 'devinput-64'
902019-12-06 08:55:11.359 T:2963271888 DEBUG: CIRTranslator::Load - no userdata Lircmap.xml found, skipping
912019-12-06 08:55:11.391 T:2973264992 NOTICE: Running database version Addons27
922019-12-06 08:55:11.556 T:2952786128 DEBUG: CLibInputHandler::DeviceAdded - keyboard type device added: OSMC Remote Controller USB Keyboard Mouse (event0)
932019-12-06 08:55:11.556 T:2952786128 DEBUG: CLibInputKeyboard::GetRepeat - delay: 250ms repeat: 33ms for OSMC Remote Controller USB Keyboard Mouse (event0)
942019-12-06 08:55:11.567 T:2973264992 INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/lib/arm-linux-gnueabihf/kodi/addons: No such file or directory'
952019-12-06 08:55:11.567 T:2973264992 DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
962019-12-06 08:55:11.567 T:2973264992 NOTICE: ADDON: audioencoder.kodi.builtin.aac v1.0.0 installed
972019-12-06 08:55:11.567 T:2973264992 NOTICE: ADDON: audioencoder.kodi.builtin.wma v1.0.0 installed
982019-12-06 08:55:11.567 T:2973264992 NOTICE: ADDON: game.controller.default v1.0.8 installed
992019-12-06 08:55:11.567 T:2973264992 NOTICE: ADDON: game.controller.snes v1.0.8 installed
1002019-12-06 08:55:11.567 T:2973264992 NOTICE: ADDON: inputstream.adaptive v2.3.22 installed
1012019-12-06 08:55:11.567 T:2973264992 NOTICE: ADDON: inputstream.rtmp v2.0.5 installed
1022019-12-06 08:55:11.567 T:2973264992 NOTICE: ADDON: kodi.binary.global.audioengine v1.0.1 installed
1032019-12-06 08:55:11.567 T:2973264992 NOTICE: ADDON: kodi.binary.global.filesystem v1.0.2 installed
1042019-12-06 08:55:11.567 T:2973264992 NOTICE: ADDON: kodi.binary.global.general v1.0.3 installed
1052019-12-06 08:55:11.567 T:2973264992 NOTICE: ADDON: kodi.binary.global.gui v5.12.0 installed
1062019-12-06 08:55:11.567 T:2973264992 NOTICE: ADDON: kodi.binary.global.main v1.0.14 installed
1072019-12-06 08:55:11.567 T:2973264992 NOTICE: ADDON: kodi.binary.global.network v1.0.0 installed
1082019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: kodi.binary.instance.audiodecoder v2.0.0 installed
1092019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: kodi.binary.instance.audioencoder v2.0.0 installed
1102019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: kodi.binary.instance.game v1.1.0 installed
1112019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: kodi.binary.instance.imagedecoder v2.0.0 installed
1122019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: kodi.binary.instance.inputstream v2.0.8 installed
1132019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: kodi.binary.instance.peripheral v1.3.7 installed
1142019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: kodi.binary.instance.pvr v5.10.3 installed
1152019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: kodi.binary.instance.screensaver v2.0.0 installed
1162019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: kodi.binary.instance.vfs v2.0.0 installed
1172019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: kodi.binary.instance.videocodec v1.0.1 installed
1182019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: kodi.binary.instance.visualization v2.0.1 installed
1192019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: kodi.resource v1.0.0 installed
1202019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: metadata.album.universal v3.1.2 installed
1212019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: metadata.artists.universal v4.3.2 installed
1222019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: metadata.common.allmusic.com v3.2.1 installed
1232019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: metadata.common.fanart.tv v3.6.2 installed
1242019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: metadata.common.imdb.com v3.1.4 installed
1252019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: metadata.common.musicbrainz.org v2.2.1 installed
1262019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: metadata.common.theaudiodb.com v2.0.3 installed
1272019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: metadata.common.themoviedb.org v3.1.10 installed
1282019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: metadata.local v1.0.0 installed
1292019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: metadata.themoviedb.org v5.1.10 installed
1302019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: metadata.tvshows.themoviedb.org v3.1.4 installed
1312019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: pvr.hts v4.4.18 installed
1322019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: repository.xbmc.org v3.1.6 installed
1332019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: resource.images.weathericons.default v1.1.8 installed
1342019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: resource.language.en_gb v2.0.1 installed
1352019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: resource.uisounds.kodi v1.0.0 installed
1362019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: screensaver.xbmc.builtin.black v1.0.33 installed
1372019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: screensaver.xbmc.builtin.dim v1.0.57 installed
1382019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: script.module.arrow v0.10.0 installed
1392019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: script.module.beautifulsoup v3.2.1 installed
1402019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: script.module.certifi v2019.9.11 installed
1412019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: script.module.chardet v3.0.4 installed
1422019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: script.module.dateutil v2.8.0 installed
1432019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: script.module.idna v2.8 installed
1442019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: script.module.metadatautils v1.0.0 installed
1452019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: script.module.musicbrainz v0.6.0 installed
1462019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: script.module.pil v1.1.7 installed
1472019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: script.module.pycryptodome v3.4.3 installed
1482019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: script.module.requests v2.22.0 installed
1492019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: script.module.simplecache v1.0.17 installed
1502019-12-06 08:55:11.568 T:2973264992 NOTICE: ADDON: script.module.simplejson v3.16.1 installed
1512019-12-06 08:55:11.569 T:2973264992 NOTICE: ADDON: script.module.six v1.11.0 installed
1522019-12-06 08:55:11.569 T:2973264992 NOTICE: ADDON: script.module.thetvdb v1.0.12 installed
1532019-12-06 08:55:11.569 T:2973264992 NOTICE: ADDON: script.module.urllib3 v1.25.6 installed
1542019-12-06 08:55:11.569 T:2973264992 NOTICE: ADDON: script.skin.helper.widgets v1.0.22 installed
1552019-12-06 08:55:11.569 T:2973264992 NOTICE: ADDON: service.xbmc.versioncheck v0.4.7 installed
1562019-12-06 08:55:11.569 T:2973264992 NOTICE: ADDON: skin.estouchy v2.0.26 installed
1572019-12-06 08:55:11.569 T:2973264992 NOTICE: ADDON: skin.estuary v2.0.25 installed
1582019-12-06 08:55:11.569 T:2973264992 NOTICE: ADDON: skin.estuary.mod v2.0.22 installed
1592019-12-06 08:55:11.569 T:2973264992 NOTICE: ADDON: vfs.nfs v1.0.1 installed
1602019-12-06 08:55:11.569 T:2973264992 NOTICE: ADDON: webinterface.default v18.x-2.4.6 installed
1612019-12-06 08:55:11.569 T:2973264992 NOTICE: ADDON: xbmc.addon v18.4.0 installed
1622019-12-06 08:55:11.569 T:2973264992 NOTICE: ADDON: xbmc.core v0.1.0 installed
1632019-12-06 08:55:11.569 T:2973264992 NOTICE: ADDON: xbmc.gui v5.14.0 installed
1642019-12-06 08:55:11.569 T:2973264992 NOTICE: ADDON: xbmc.json v10.3.0 installed
1652019-12-06 08:55:11.569 T:2973264992 NOTICE: ADDON: xbmc.metadata v2.1.0 installed
1662019-12-06 08:55:11.569 T:2973264992 NOTICE: ADDON: xbmc.python v2.26.0 installed
1672019-12-06 08:55:11.569 T:2973264992 NOTICE: ADDON: xbmc.webinterface v1.0.0 installed
1682019-12-06 08:55:11.602 T:2973264992 DEBUG: CFavourites::Load - no system favourites found, skipping
1692019-12-06 08:55:11.602 T:2973264992 DEBUG: CFavourites::Load - no userdata favourites found, skipping
1702019-12-06 08:55:11.602 T:2952786128 DEBUG: CLibInputHandler::DeviceAdded - pointer type device added: OSMC Remote Controller USB Keyboard Mouse Mouse (event1)
1712019-12-06 08:55:11.602 T:2952786128 DEBUG: CLibInputHandler::DeviceAdded - pointer type device added: OSMC Remote Controller USB Keyboard Mouse Consumer Control (event2)
1722019-12-06 08:55:11.602 T:2952786128 DEBUG: CLibInputHandler::DeviceAdded - keyboard type device added: OSMC Remote Controller USB Keyboard Mouse Consumer Control (event2)
1732019-12-06 08:55:11.602 T:2952786128 DEBUG: CLibInputKeyboard::GetRepeat - could not get key repeat for event2 (Function not implemented)
1742019-12-06 08:55:11.602 T:2952786128 DEBUG: CLibInputKeyboard::GetRepeat - delay: 400ms repeat: 80ms for OSMC Remote Controller USB Keyboard Mouse Consumer Control (event2)
1752019-12-06 08:55:11.604 T:2973264992 DEBUG: DBus method call to org.freedesktop.ConsoleKit.Manager.CanStop at /org/freedesktop/ConsoleKit/Manager of org.freedesktop.ConsoleKit failed: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.ConsoleKit was not provided by any .service files
1762019-12-06 08:55:11.604 T:2973264992 INFO: Selected Logind/UPower as PowerSyscall
1772019-12-06 08:55:11.605 T:2973264992 ERROR: DBus error: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files
1782019-12-06 08:55:11.605 T:2973264992 INFO: LogindUPowerSyscall - UPower not found, battery information will not be available
1792019-12-06 08:55:11.642 T:2952786128 DEBUG: CLibInputHandler::DeviceAdded - keyboard type device added: OSMC Remote Controller USB Keyboard Mouse System Control (event3)
1802019-12-06 08:55:11.642 T:2952786128 DEBUG: CLibInputKeyboard::GetRepeat - could not get key repeat for event3 (Function not implemented)
1812019-12-06 08:55:11.642 T:2952786128 DEBUG: CLibInputKeyboard::GetRepeat - delay: 400ms repeat: 80ms for OSMC Remote Controller USB Keyboard Mouse System Control (event3)
1822019-12-06 08:55:11.692 T:2952786128 DEBUG: CLibInputHandler::DeviceAdded - keyboard type device added: Astrometa DVB-T2 (event4)
1832019-12-06 08:55:11.692 T:2952786128 DEBUG: CLibInputKeyboard::GetRepeat - delay: 500ms repeat: 125ms for Astrometa DVB-T2 (event4)
1842019-12-06 08:55:11.743 T:2973264992 DEBUG: LogindUPowerSyscall - inhibit lock taken, fd 22
1852019-12-06 08:55:11.745 T:2973264992 DEBUG: DBus method call to org.freedesktop.UPower.EnumerateDevices at /org/freedesktop/UPower of org.freedesktop.UPower failed: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files
1862019-12-06 08:55:11.745 T:2942300368 DEBUG: Thread ActiveAE start, auto delete: false
1872019-12-06 08:55:11.746 T:2931814608 DEBUG: Thread AESink start, auto delete: false
1882019-12-06 08:55:11.746 T:2921328848 DEBUG: Thread FDEventMonitor start, auto delete: false
1892019-12-06 08:55:11.762 T:2942300368 INFO: CAESinkALSA - Unable to open device "default" for playback
1902019-12-06 08:55:11.800 T:2942300368 INFO: CAESinkALSA - Unable to open device "jack" for playback
1912019-12-06 08:55:11.805 T:2942300368 INFO: CAESinkALSA - Unable to open device "pulse" for playback
1922019-12-06 08:55:11.808 T:2942300368 INFO: CAESinkALSA - Unable to open device "output" for playback
1932019-12-06 08:55:11.811 T:2942300368 INFO: CAESinkALSA - Unable to open device "input" for playback
1942019-12-06 08:55:11.815 T:2942300368 INFO: CAESinkALSA - Unable to open device "usbstream:CARD=ALSA" for playback
1952019-12-06 08:55:11.815 T:2942300368 NOTICE: Found 2 Lists of Devices
1962019-12-06 08:55:11.815 T:2942300368 NOTICE: Enumerated ALSA devices:
1972019-12-06 08:55:11.815 T:2942300368 NOTICE: Device 1
1982019-12-06 08:55:11.816 T:2942300368 NOTICE: m_deviceName : sysdefault:CARD=ALSA
1992019-12-06 08:55:11.816 T:2942300368 NOTICE: m_displayName : bcm2835 ALSA
2002019-12-06 08:55:11.816 T:2942300368 NOTICE: m_displayNameExtra: bcm2835 ALSA
2012019-12-06 08:55:11.816 T:2942300368 NOTICE: m_deviceType : AE_DEVTYPE_PCM
2022019-12-06 08:55:11.816 T:2942300368 NOTICE: m_channels : FL, FR
2032019-12-06 08:55:11.816 T:2942300368 NOTICE: m_sampleRates : 8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000
2042019-12-06 08:55:11.816 T:2942300368 NOTICE: m_dataFormats : AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_U8
2052019-12-06 08:55:11.816 T:2942300368 NOTICE: m_streamTypes : No passthrough capabilities
2062019-12-06 08:55:11.816 T:2942300368 NOTICE: Enumerated PI devices:
2072019-12-06 08:55:11.816 T:2942300368 NOTICE: Device 1
2082019-12-06 08:55:11.816 T:2942300368 NOTICE: m_deviceName : HDMI
2092019-12-06 08:55:11.816 T:2942300368 NOTICE: m_displayName : HDMI
2102019-12-06 08:55:11.816 T:2942300368 NOTICE: m_displayNameExtra:
2112019-12-06 08:55:11.816 T:2942300368 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
2122019-12-06 08:55:11.816 T:2942300368 NOTICE: m_channels : FL, FR
2132019-12-06 08:55:11.816 T:2942300368 NOTICE: m_sampleRates : 8000,11025,16000,22050,24000,32000,44100,48000,88200,96000,176400,192000
2142019-12-06 08:55:11.816 T:2942300368 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP,AE_FMT_RAW
2152019-12-06 08:55:11.816 T:2942300368 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_EAC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_512
2162019-12-06 08:55:11.816 T:2942300368 NOTICE: Device 2
2172019-12-06 08:55:11.816 T:2942300368 NOTICE: m_deviceName : Analogue
2182019-12-06 08:55:11.816 T:2942300368 NOTICE: m_displayName : Analogue
2192019-12-06 08:55:11.816 T:2942300368 NOTICE: m_displayNameExtra:
2202019-12-06 08:55:11.816 T:2942300368 NOTICE: m_deviceType : AE_DEVTYPE_PCM
2212019-12-06 08:55:11.816 T:2942300368 NOTICE: m_channels : FL, FR
2222019-12-06 08:55:11.816 T:2942300368 NOTICE: m_sampleRates : 48000
2232019-12-06 08:55:11.816 T:2942300368 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
2242019-12-06 08:55:11.816 T:2942300368 NOTICE: m_streamTypes : No passthrough capabilities
2252019-12-06 08:55:11.816 T:2942300368 NOTICE: Device 3
2262019-12-06 08:55:11.816 T:2942300368 NOTICE: m_deviceName : Both
2272019-12-06 08:55:11.816 T:2942300368 NOTICE: m_displayName : HDMI and Analogue
2282019-12-06 08:55:11.816 T:2942300368 NOTICE: m_displayNameExtra:
2292019-12-06 08:55:11.816 T:2942300368 NOTICE: m_deviceType : AE_DEVTYPE_PCM
2302019-12-06 08:55:11.816 T:2942300368 NOTICE: m_channels : FL, FR
2312019-12-06 08:55:11.816 T:2942300368 NOTICE: m_sampleRates : 48000
2322019-12-06 08:55:11.817 T:2942300368 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
2332019-12-06 08:55:11.817 T:2942300368 NOTICE: m_streamTypes : No passthrough capabilities
2342019-12-06 08:55:11.817 T:2931814608 INFO: CActiveAESink::OpenSink - initialize sink
2352019-12-06 08:55:11.817 T:2931814608 DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI
2362019-12-06 08:55:11.817 T:2931814608 DEBUG: DllBcm: Using omx system library
2372019-12-06 08:55:11.818 T:2931814608 DEBUG: SECTION:LoadDLL(libopenmaxil.so)
2382019-12-06 08:55:11.820 T:2931814608 DEBUG: Loading: libopenmaxil.so
2392019-12-06 08:55:11.830 T:2931814608 DEBUG: CSettingsManager: requested setting (videoscreen.limitgui) was not found.
2402019-12-06 08:55:11.831 T:2931814608 DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:17640 bytes/s=352800.00 dest=PI:HDMI
2412019-12-06 08:55:11.831 T:2931814608 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008
2422019-12-06 08:55:11.832 T:2931814608 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0xae203240
2432019-12-06 08:55:11.834 T:2931814608 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(17648), nBufferAlignment(16)
2442019-12-06 08:55:11.834 T:2931814608 DEBUG: CActiveAESink::OpenSink - SinkPi Initialized:
2452019-12-06 08:55:11.834 T:2931814608 DEBUG: Output Device : HDMI
2462019-12-06 08:55:11.834 T:2931814608 DEBUG: Sample Rate : 44100
2472019-12-06 08:55:11.834 T:2931814608 DEBUG: Sample Format : AE_FMT_FLOAT
2482019-12-06 08:55:11.834 T:2931814608 DEBUG: Channel Count : 2
2492019-12-06 08:55:11.834 T:2931814608 DEBUG: Channel Layout: FL, FR
2502019-12-06 08:55:11.834 T:2931814608 DEBUG: Frames : 2205
2512019-12-06 08:55:11.834 T:2931814608 DEBUG: Frame Size : 8
2522019-12-06 08:55:11.847 T:2973264992 INFO: CKeyboardLayoutManager: loading keyboard layouts from special://xbmc/system/keyboardlayouts...
2532019-12-06 08:55:11.851 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian ЯВЕРТЪ" successfully loaded
2542019-12-06 08:55:11.853 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian АБВ" successfully loaded
2552019-12-06 08:55:11.855 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Thai Kedmanee" successfully loaded
2562019-12-06 08:55:11.857 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Portuguese (Portugal) QWERTY" successfully loaded
2572019-12-06 08:55:11.859 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "English QWERTY" successfully loaded
2582019-12-06 08:55:11.860 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "English AZERTY" successfully loaded
2592019-12-06 08:55:11.861 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "English ABC" successfully loaded
2602019-12-06 08:55:11.863 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Czech QWERTZ" successfully loaded
2612019-12-06 08:55:11.865 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BasePY" successfully loaded
2622019-12-06 08:55:11.866 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BaiduPY" successfully loaded
2632019-12-06 08:55:11.868 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Greek QWERTY" successfully loaded
2642019-12-06 08:55:11.870 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovenian QWERTZ" successfully loaded
2652019-12-06 08:55:11.872 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Polish QWERTY" successfully loaded
2662019-12-06 08:55:11.873 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Hungarian QWERTZ" successfully loaded
2672019-12-06 08:55:11.874 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Italian QWERTY" successfully loaded
2682019-12-06 08:55:11.875 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Turkish QWERTY" successfully loaded
2692019-12-06 08:55:11.876 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Korean ㄱㄴㄷ" successfully loaded
2702019-12-06 08:55:11.877 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew QWERTY" successfully loaded
2712019-12-06 08:55:11.877 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew ABC" successfully loaded
2722019-12-06 08:55:11.878 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian ЙЦУКЕН" successfully loaded
2732019-12-06 08:55:11.878 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian АБВ" successfully loaded
2742019-12-06 08:55:11.879 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Danish QWERTY" successfully loaded
2752019-12-06 08:55:11.880 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Silesian QWERTY" successfully loaded
2762019-12-06 08:55:11.881 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "French AZERTY" successfully loaded
2772019-12-06 08:55:11.882 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTZ" successfully loaded
2782019-12-06 08:55:11.882 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTY" successfully loaded
2792019-12-06 08:55:11.882 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak ABC" successfully loaded
2802019-12-06 08:55:11.883 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian ЙЦУКЕН" successfully loaded
2812019-12-06 08:55:11.883 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian АБВ" successfully loaded
2822019-12-06 08:55:11.884 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Romanian QWERTY" successfully loaded
2832019-12-06 08:55:11.885 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian AZERTY" successfully loaded
2842019-12-06 08:55:11.885 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian QWERTY" successfully loaded
2852019-12-06 08:55:11.886 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "German QWERTZ" successfully loaded
2862019-12-06 08:55:11.887 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "German ABC" successfully loaded
2872019-12-06 08:55:11.887 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Norwegian QWERTY" successfully loaded
2882019-12-06 08:55:11.888 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Arabic QWERTY" successfully loaded
2892019-12-06 08:55:11.889 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Swedish QWERTY" successfully loaded
2902019-12-06 08:55:11.890 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Croatian QWERTY" successfully loaded
2912019-12-06 08:55:11.891 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Spanish QWERTY" successfully loaded
2922019-12-06 08:55:11.892 T:2973264992 DEBUG: CKeyboardLayoutManager: keyboard layout "Icelandic QWERTY" successfully loaded
2932019-12-06 08:55:12.575 T:2973264992 DEBUG: Selected UDisks2 as storage provider
2942019-12-06 08:55:12.578 T:2973264992 DEBUG: UDisks2: Daemon version 2.8.1
2952019-12-06 08:55:12.578 T:2973264992 DEBUG: UDisks2: Querying available devices
2962019-12-06 08:55:12.602 T:2973264992 DEBUG: UDisks2: Mounting /dev/mmcblk0p2
2972019-12-06 08:55:12.778 T:2973264992 NOTICE: Raspberry PI firmware version: Dec 5 2019 14:41:08
298 Copyright (c) 2012 Broadcom
299 version dfab159feffeba8de79ecb6dc973fab33d07004f (clean) (release) (start)
3002019-12-06 08:55:12.778 T:2973264992 NOTICE: ARM mem: 512MB GPU mem: 512MB MPG2:0 WVC1:0
3012019-12-06 08:55:12.778 T:2973264992 NOTICE: Config:
302 arm_freq=1500
303 audio_pwm_mode=514
304 config_hdmi_boost=5
305 core_freq=550
306 core_freq_min=220
307 disable_commandline_tags=2
308 disable_l2cache=1
309 disable_overscan=1
310 display_hdmi_rotate=-1
311 display_lcd_rotate=-1
312 enable_gic=1
313 force_eeprom_read=1
314 force_pwm_open=1
315 framebuffer_ignore_alpha=1
316 framebuffer_swap=1
317 gpu_freq=500
318 gpu_freq_min=250
319 hdmi_channel_map=8
320 hdmi_enable_4kp60=1
321 hdmi_samples_limit=0x1f4000
322 init_uart_clock=0x2dc6c00
323 lcd_framerate=60
324 mask_gpu_interrupt0=1024
325 mask_gpu_interrupt1=0x10000
326 max_framebuffer_height=2160
327 max_framebuffer_width=3840
328 max_framebuffers=2
329 over_voltage_avs=-17500
330 pause_burst_frames=1
331 program_serial_random=1
332 total_mem=2048
333 hdmi_force_cec_address:0=65535
334 hdmi_force_cec_address:1=65535
335 hdmi_ignore_cec_init:0=1
336 hdmi_pixel_freq_limit:0=0x23c34600
337 hdmi_pixel_freq_limit:1=0x9a7ec80
3382019-12-06 08:55:12.778 T:2973264992 NOTICE: Config:
339 device_tree=-
340 overlay_prefix=overlays/
341 hdmi_cvt:0=
342 hdmi_cvt:1=
343 hdmi_edid_filename:0=
344 hdmi_edid_filename:1=
345 hdmi_timings:0=
346 hdmi_timings:1=
3472019-12-06 08:55:12.779 T:2973264992 INFO: RetroPlayer[PROCESS]: Registering process control for GBM
3482019-12-06 08:55:12.779 T:2973264992 INFO: RetroPlayer[RENDER]: Registering renderer factory for OpenGLES
3492019-12-06 08:55:13.413 T:2973264992 DEBUG: CDRMUtils::FindConnector - found connector: 51
3502019-12-06 08:55:13.413 T:2973264992 DEBUG: CDRMUtils::OpenDrm - opened device: /dev/dri/card1 using module: vc4
3512019-12-06 08:55:13.513 T:2973264992 DEBUG: CDRMUtils::FindConnector - found connector: 51
3522019-12-06 08:55:13.513 T:2973264992 DEBUG: CDRMUtils::FindEncoder - found encoder: 50
3532019-12-06 08:55:13.513 T:2973264992 DEBUG: CDRMUtils::FindCrtc - found crtc: 49
3542019-12-06 08:55:13.513 T:2973264992 DEBUG: CDRMUtils::FindPlane - found video plane 28
3552019-12-06 08:55:13.514 T:2973264992 WARNING: CDRMUtils::FindPlane - could not find plane
3562019-12-06 08:55:13.514 T:2973264992 DEBUG: CDRMUtils::FindPlane - found gui plane 35
3572019-12-06 08:55:13.514 T:2973264992 DEBUG: CDRMUtils::FindPreferredMode - found preferred mode: 1920x1080 @ 60 Hz
3582019-12-06 08:55:13.514 T:2973264992 WARNING: CDRMUtils::InitDrm - failed to set drm master, will try to authorize instead: Permission denied
3592019-12-06 08:55:13.514 T:2973264992 NOTICE: CDRMUtils::InitDrm - successfully authorized drm magic
3602019-12-06 08:55:13.515 T:2973264992 DEBUG: CDRMAtomic::InitDrm - initialized atomic DRM
3612019-12-06 08:55:14.438 T:2973264992 DEBUG: CWinSystemGbm::InitWindowSystem - initialized DRM
3622019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 1920x1080 with 1920x1080 @ 60.000000 Hz
3632019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 1920x1080 with 1920x1080 @ 59.940063 Hz
3642019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 1920x1080 with 1920x1080 @ 50.000000 Hz
3652019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 1920x1080 with 1920x1080 @ 30.000000 Hz
3662019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 1920x1080 with 1920x1080 @ 29.970032 Hz
3672019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 1920x1080 with 1920x1080 @ 25.000000 Hz
3682019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 1920x1080 with 1920x1080 @ 24.000000 Hz
3692019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 1920x1080 with 1920x1080 @ 23.976025 Hz
3702019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 1280x1024 with 1280x1024 @ 60.000000 Hz
3712019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 1360x768 with 1360x768 @ 60.000000 Hz
3722019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 1280x720 with 1280x720 @ 60.000000 Hz
3732019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 1280x720 with 1280x720 @ 59.940063 Hz
3742019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 1280x720 with 1280x720 @ 50.000000 Hz
3752019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 1024x768 with 1024x768 @ 60.000000 Hz
3762019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 800x600 with 800x600 @ 60.000000 Hz
3772019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 720x576 with 720x576 @ 50.000000 Hz
3782019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 720x480 with 720x480 @ 59.940063 Hz
3792019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 720x480 with 720x480 @ 60.000000 Hz
3802019-12-06 08:55:14.439 T:2973264992 NOTICE: Found resolution 640x480 with 640x480 @ 60.000000 Hz
3812019-12-06 08:55:14.493 T:2973264992 NOTICE: Previous line repeats 1 times.
3822019-12-06 08:55:14.494 T:2973264992 NOTICE: EGL_VERSION = 1.4
3832019-12-06 08:55:14.494 T:2973264992 NOTICE: EGL_VENDOR = Mesa Project
3842019-12-06 08:55:14.494 T:2973264992 NOTICE: EGL_EXTENSIONS = EGL_ANDROID_blob_cache EGL_EXT_buffer_age EGL_EXT_image_dma_buf_import EGL_EXT_image_dma_buf_import_modifiers EGL_KHR_cl_event2 EGL_KHR_config_attribs EGL_KHR_create_context EGL_KHR_create_context_no_error EGL_KHR_fence_sync EGL_KHR_get_all_proc_addresses EGL_KHR_gl_colorspace EGL_KHR_gl_renderbuffer_image EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_3D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_KHR_no_config_context EGL_KHR_reusable_sync EGL_KHR_surfaceless_context EGL_EXT_pixel_format_float EGL_KHR_wait_sync EGL_MESA_configless_context EGL_MESA_drm_image EGL_MESA_image_dma_buf_export EGL_WL_bind_wayland_display
3852019-12-06 08:55:14.494 T:2973264992 NOTICE: EGL_CLIENT_EXTENSIONS = EGL_EXT_device_base EGL_EXT_device_enumeration EGL_EXT_device_query EGL_EXT_platform_base EGL_KHR_client_get_all_proc_addresses EGL_EXT_client_extensions EGL_KHR_debug EGL_EXT_platform_wayland EGL_EXT_platform_x11 EGL_MESA_platform_gbm EGL_MESA_platform_surfaceless EGL_EXT_platform_device
3862019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL Config Attributes:
3872019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_BUFFER_SIZE: 32
3882019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_ALPHA_SIZE: 8
3892019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_BLUE_SIZE: 8
3902019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_GREEN_SIZE: 8
3912019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_RED_SIZE: 8
3922019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_DEPTH_SIZE: 16
3932019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_STENCIL_SIZE: 0
3942019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_CONFIG_CAVEAT: 0x3038
3952019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_CONFIG_ID: 10
3962019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_LEVEL: 0
3972019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_MAX_PBUFFER_HEIGHT: 4096
3982019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_MAX_PBUFFER_PIXELS: 0
3992019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_MAX_PBUFFER_WIDTH: 4096
4002019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_NATIVE_RENDERABLE: 1
4012019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_NATIVE_VISUAL_ID: 875713089
4022019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_NATIVE_VISUAL_TYPE: 0x3038
4032019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_SAMPLES: 0
4042019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_SAMPLE_BUFFERS: 0
4052019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_SURFACE_TYPE: 4
4062019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_TRANSPARENT_TYPE: 0x3038
4072019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_TRANSPARENT_BLUE_VALUE: 0
4082019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_TRANSPARENT_GREEN_VALUE: 0
4092019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_TRANSPARENT_RED_VALUE: 0
4102019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_BIND_TO_TEXTURE_RGB: 0
4112019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_BIND_TO_TEXTURE_RGBA: 0
4122019-12-06 08:55:14.494 T:2973264992 DEBUG: EGL_MIN_SWAP_INTERVAL: 1
4132019-12-06 08:55:14.495 T:2973264992 DEBUG: EGL_MAX_SWAP_INTERVAL: 1
4142019-12-06 08:55:14.495 T:2973264992 DEBUG: EGL_LUMINANCE_SIZE: 0
4152019-12-06 08:55:14.495 T:2973264992 DEBUG: EGL_ALPHA_MASK_SIZE: 0
4162019-12-06 08:55:14.495 T:2973264992 DEBUG: EGL_COLOR_BUFFER_TYPE: 0x308e
4172019-12-06 08:55:14.495 T:2973264992 DEBUG: EGL_RENDERABLE_TYPE: 77
4182019-12-06 08:55:14.495 T:2973264992 DEBUG: EGL_CONFORMANT: 77
4192019-12-06 08:55:14.507 T:2973264992 NOTICE: Checking resolution 19
4202019-12-06 08:55:14.508 T:2973264992 DEBUG: OnLostDevice - notify display change event
4212019-12-06 08:55:14.642 T:2931814608 DEBUG: CAESinkPi:Drain delay:100ms now:0ms
4222019-12-06 08:55:14.642 T:2931814608 DEBUG: CAESinkPi:Deinitialize
4232019-12-06 08:55:14.643 T:2931814608 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000
4242019-12-06 08:55:14.652 T:2931814608 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0xae203240
4252019-12-06 08:55:14.653 T:2973264992 WARNING: CGBMUtils::DestroySurface - surface already destroyed
4262019-12-06 08:55:14.653 T:2973264992 DEBUG: CWinSystemGbmEGLContext::DestroyWindow - deinitialized GBM
4272019-12-06 08:55:14.686 T:2973264992 DEBUG: CDRMUtils::SetMode - found crtc mode: 1920x1080 @ 50 Hz
4282019-12-06 08:55:14.686 T:2973264992 DEBUG: CGBMUtils::CreateSurface - created surface with size 1920x1080
4292019-12-06 08:55:14.699 T:2973264992 DEBUG: CWinSystemGbmEGLContext::CreateNewWindow - initialized GBM
4302019-12-06 08:55:14.700 T:2973264992 NOTICE: GL_VENDOR = Broadcom
4312019-12-06 08:55:14.700 T:2973264992 NOTICE: GL_RENDERER = V3D 4.2
4322019-12-06 08:55:14.700 T:2973264992 NOTICE: GL_VERSION = OpenGL ES 3.0 Mesa 19.2.0-rc1
4332019-12-06 08:55:14.700 T:2973264992 NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 3.00
4342019-12-06 08:55:14.700 T:2973264992 NOTICE: GL_EXTENSIONS = GL_EXT_blend_minmax GL_EXT_multi_draw_arrays GL_EXT_texture_format_BGRA8888 GL_OES_compressed_ETC1_RGB8_texture GL_OES_depth24 GL_OES_element_index_uint GL_OES_fbo_render_mipmap GL_OES_mapbuffer GL_OES_rgb8_rgba8 GL_OES_standard_derivatives GL_OES_stencil8 GL_OES_texture_3D GL_OES_texture_float GL_OES_texture_half_float GL_OES_texture_half_float_linear GL_OES_texture_npot GL_OES_vertex_half_float GL_EXT_texture_sRGB_decode GL_OES_EGL_image GL_OES_depth_texture GL_OES_packed_depth_stencil GL_EXT_texture_type_2_10_10_10_REV GL_OES_get_program_binary GL_APPLE_texture_max_level GL_EXT_discard_framebuffer GL_EXT_read_format_bgra GL_EXT_frag_depth GL_NV_fbo_color_attachments GL_OES_EGL_image_external GL_OES_EGL_sync GL_OES_vertex_array_object GL_EXT_occlusion_query_boolean GL_EXT_texture_rg GL_EXT_unpack_subimage GL_NV_draw_buffers GL_NV_read_buffer GL_NV_read_depth GL_NV_read_depth_stencil GL_NV_read_stencil GL_EXT_draw_buffers GL_EXT_map_buffer_range GL_KHR_debug GL_KHR_texture_compression_astc_ldr GL_OES_depth_texture_cube_map GL_OES_required_internalformat GL_OES_surfaceless_context GL_EXT_color_buffer_float GL_EXT_sRGB_write_control GL_EXT_separate_shader_objects GL_EXT_shader_integer_mix GL_EXT_base_instance GL_EXT_compressed_ETC1_RGB8_sub_texture GL_EXT_draw_elements_base_vertex GL_EXT_texture_border_clamp GL_KHR_context_flush_control GL_OES_draw_elements_base_vertex GL_OES_texture_border_clamp GL_OES_texture_stencil8 GL_EXT_float_blend GL_KHR_no_error GL_KHR_texture_compression_astc_sliced_3d GL_OES_EGL_image_external_essl3 GL_MESA_shader_integer_functions GL_KHR_parallel_shader_compile GL_EXT_texture_query_lod
4352019-12-06 08:55:14.787 T:2973264992 DEBUG: OnLostDevice - notify display change event
4362019-12-06 08:55:14.820 T:2973264992 DEBUG: CDRMUtils::SetMode - found crtc mode: 1920x1080 @ 50 Hz
4372019-12-06 08:55:14.820 T:2973264992 INFO: GLES: Maximum texture width: 4096
4382019-12-06 08:55:14.820 T:2942300368 DEBUG: CActiveAE - display reset event
4392019-12-06 08:55:14.820 T:2973264992 DEBUG: guilib: Fill viewport on change for solving rendering passes
4402019-12-06 08:55:14.820 T:2931814608 INFO: CActiveAESink::OpenSink - initialize sink
4412019-12-06 08:55:14.820 T:2931814608 DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI
4422019-12-06 08:55:14.821 T:2931814608 DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:17640 bytes/s=352800.00 dest=PI:HDMI
4432019-12-06 08:55:14.821 T:2931814608 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008
4442019-12-06 08:55:14.822 T:2931814608 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0xae203240
4452019-12-06 08:55:14.824 T:2931814608 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(17648), nBufferAlignment(16)
4462019-12-06 08:55:14.824 T:2931814608 DEBUG: CActiveAESink::OpenSink - SinkPi Initialized:
4472019-12-06 08:55:14.824 T:2931814608 DEBUG: Output Device : HDMI
4482019-12-06 08:55:14.824 T:2931814608 DEBUG: Sample Rate : 44100
4492019-12-06 08:55:14.824 T:2931814608 DEBUG: Sample Format : AE_FMT_FLOAT
4502019-12-06 08:55:14.825 T:2931814608 DEBUG: Channel Count : 2
4512019-12-06 08:55:14.825 T:2931814608 DEBUG: Channel Layout: FL, FR
4522019-12-06 08:55:14.825 T:2931814608 DEBUG: Frames : 2205
4532019-12-06 08:55:14.825 T:2931814608 DEBUG: Frame Size : 8
4542019-12-06 08:55:14.826 T:2942300368 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
4552019-12-06 08:55:15.211 T:2973264992 DEBUG: EGL Debugging:
456 Error: EGL_BAD_SURFACE
457 Command: eglSwapBuffers
458 Type: EGL_DEBUG_MSG_ERROR_KHR
459 Message: dri2_swap_buffers
4602019-12-06 08:55:15.365 T:2973264992 DEBUG: CRBP::OpenDisplay device:0 m_display:10000020 (0)
4612019-12-06 08:55:15.365 T:2973264992 INFO: load keymapping
4622019-12-06 08:55:15.367 T:2973264992 INFO: Loading special://xbmc/system/keymaps/appcommand.xml
4632019-12-06 08:55:15.368 T:2973264992 INFO: Loading special://xbmc/system/keymaps/customcontroller.AppleRemote.xml
4642019-12-06 08:55:15.369 T:2973264992 INFO: Loading special://xbmc/system/keymaps/customcontroller.Harmony.xml
4652019-12-06 08:55:15.371 T:2973264992 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
4662019-12-06 08:55:15.374 T:2973264992 INFO: Loading special://xbmc/system/keymaps/joystick.xml
4672019-12-06 08:55:15.376 T:2973264992 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
4682019-12-06 08:55:15.381 T:2973264992 INFO: Loading special://xbmc/system/keymaps/mouse.xml
4692019-12-06 08:55:15.382 T:2973264992 INFO: Loading special://xbmc/system/keymaps/remote.xml
4702019-12-06 08:55:15.385 T:2973264992 INFO: Loading special://xbmc/system/keymaps/touchscreen.xml
4712019-12-06 08:55:15.388 T:2973264992 INFO: GUI format 1920x1080, Display 1920x1080 @ 50.000000 Hz
4722019-12-06 08:55:15.388 T:2973264992 INFO: CLangInfo: loading resource.language.en_gb language information...
4732019-12-06 08:55:15.389 T:2973264992 DEBUG: trying to set locale to en_US.UTF-8
4742019-12-06 08:55:15.391 T:2973264992 INFO: global locale set to C
4752019-12-06 08:55:15.392 T:2973264992 INFO: CLangInfo: loading resource.language.en_gb language strings...
4762019-12-06 08:55:15.415 T:2973264992 DEBUG: LocalizeStrings: loaded 4028 strings from file resource://resource.language.en_gb/strings.po
4772019-12-06 08:55:15.424 T:2973264992 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.kodi.builtin.aac/resources/language/English/strings.po
4782019-12-06 08:55:15.425 T:2973264992 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.kodi.builtin.wma/resources/language/English/strings.po
4792019-12-06 08:55:15.426 T:2973264992 DEBUG: LocalizeStrings: loaded 18 strings from file /usr/share/kodi/addons/game.controller.default/resources/language/resource.language.en_gb/strings.po
4802019-12-06 08:55:15.428 T:2973264992 DEBUG: LocalizeStrings: loaded 13 strings from file /usr/share/kodi/addons/game.controller.snes/resources/language/resource.language.en_gb/strings.po
4812019-12-06 08:55:15.429 T:2973264992 DEBUG: LocalizeStrings: loaded 22 strings from file /usr/share/kodi/addons/inputstream.adaptive/resources/language/resource.language.en_gb/strings.po
4822019-12-06 08:55:15.432 T:2973264992 DEBUG: LocalizeStrings: loaded 17 strings from file /home/pi/.kodi/addons/metadata.album.universal/resources/language/resource.language.en_gb/strings.po
4832019-12-06 08:55:15.434 T:2973264992 DEBUG: LocalizeStrings: loaded 32 strings from file /home/pi/.kodi/addons/metadata.artists.universal/resources/language/resource.language.en_gb/strings.po
4842019-12-06 08:55:15.437 T:2973264992 DEBUG: LocalizeStrings: loaded 8 strings from file /home/pi/.kodi/addons/metadata.themoviedb.org/resources/language/resource.language.en_gb/strings.po
4852019-12-06 08:55:15.438 T:2973264992 DEBUG: LocalizeStrings: loaded 5 strings from file /home/pi/.kodi/addons/metadata.tvshows.themoviedb.org/resources/language/resource.language.en_gb/strings.po
4862019-12-06 08:55:15.440 T:2973264992 DEBUG: LocalizeStrings: loaded 85 strings from file /usr/share/kodi/addons/pvr.hts/resources/language/resource.language.en_gb/strings.po
4872019-12-06 08:55:15.444 T:2973264992 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/screensaver.xbmc.builtin.dim/resources/language/resource.language.en_gb/strings.po
4882019-12-06 08:55:15.446 T:2973264992 DEBUG: LocalizeStrings: loaded 24 strings from file /home/pi/.kodi/addons/service.xbmc.versioncheck/resources/language/English/strings.po
4892019-12-06 08:55:15.456 T:2973264992 DEBUG: LocalizeStrings: loaded 51 strings from file /home/pi/.kodi/addons/script.module.metadatautils/resources/language/resource.language.en_gb/strings.po
4902019-12-06 08:55:15.457 T:2973264992 DEBUG: LocalizeStrings: loaded 69 strings from file /home/pi/.kodi/addons/script.skin.helper.widgets/resources/language/resource.language.en_gb/strings.po
4912019-12-06 08:55:15.458 T:2768003280 DEBUG: Thread JobWorker start, auto delete: true
4922019-12-06 08:55:15.458 T:2768003280 DEBUG: Initialize, updating databases...
4932019-12-06 08:55:15.459 T:2768003280 NOTICE: Running database version Addons27
4942019-12-06 08:55:15.460 T:2768003280 NOTICE: Running database version ViewModes6
4952019-12-06 08:55:15.462 T:2768003280 NOTICE: Running database version Textures13
4962019-12-06 08:55:15.466 T:2768003280 NOTICE: Running database version MyMusic72
4972019-12-06 08:55:15.473 T:2768003280 NOTICE: MYSQL: Connected to version 10.0.28-MariaDB-2+b1
4982019-12-06 08:55:15.479 T:2768003280 NOTICE: Running database version MyVideos116
4992019-12-06 08:55:15.480 T:2768003280 NOTICE: Running database version TV32
5002019-12-06 08:55:15.481 T:2768003280 NOTICE: Running database version Epg12
5012019-12-06 08:55:15.482 T:2768003280 DEBUG: Initialize, updating databases... DONE
5022019-12-06 08:55:15.504 T:2973264992 NOTICE: start dvd mediatype detection
5032019-12-06 08:55:15.504 T:2943463632 DEBUG: Thread DetectDVDMedia start, auto delete: false
5042019-12-06 08:55:15.504 T:2973264992 DEBUG: DPMS: supported power-saving modes: OFF
5052019-12-06 08:55:15.504 T:2943463632 DEBUG: Compiled with libcdio Version 0.20000
5062019-12-06 08:55:15.506 T:2943463632 DEBUG: Thread DetectDVDMedia 2943463632 terminating
5072019-12-06 08:55:15.524 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
5082019-12-06 08:55:15.524 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
5092019-12-06 08:55:15.544 T:2973264992 INFO: Unloading old skin ...
5102019-12-06 08:55:15.546 T:2973264992 NOTICE: load skin from: /home/pi/.kodi/addons/skin.estuary.mod (version: 2.0.22)
5112019-12-06 08:55:15.546 T:2973264992 INFO: load fonts for skin...
5122019-12-06 08:55:15.548 T:2973264992 INFO: Loading colors from /home/pi/.kodi/addons/skin.estuary.mod/colors/gblu.xml
5132019-12-06 08:55:15.549 T:2973264992 INFO: Loading skin includes from /home/pi/.kodi/addons/skin.estuary.mod/xml/Includes.xml
5142019-12-06 08:55:15.604 T:2973264992 INFO: Loading fonts from /home/pi/.kodi/addons/skin.estuary.mod/xml/Font.xml
5152019-12-06 08:55:15.640 T:2973264992 DEBUG: LocalizeStrings: loaded 140 strings from file /home/pi/.kodi/addons/skin.estuary.mod/language/resource.language.en_gb/strings.po
5162019-12-06 08:55:15.640 T:2973264992 INFO: load new skin...
5172019-12-06 08:55:15.640 T:2973264992 INFO: Loading custom window XMLs from skin path /home/pi/.kodi/addons/skin.estuary.mod/xml
5182019-12-06 08:55:15.650 T:2973264992 DEBUG: Load Skin XML: 10.02ms
5192019-12-06 08:55:15.650 T:2973264992 INFO: initialize new skin...
5202019-12-06 08:55:15.650 T:2973264992 DEBUG: guilib: Fill viewport on change for solving rendering passes
5212019-12-06 08:55:15.650 T:2973264992 INFO: Loading skin file: Custom_1109_TopBarOverlay.xml, load type: LOAD_ON_GUI_INIT
5222019-12-06 08:55:15.653 T:2973264992 INFO: Loading skin file: DialogNotification.xml, load type: LOAD_ON_GUI_INIT
5232019-12-06 08:55:15.655 T:2973264992 INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
5242019-12-06 08:55:15.670 T:2973264992 DEBUG: OpenBundle - Opened bundle /home/pi/.kodi/addons/skin.estuary.mod/media/curial.xbt
5252019-12-06 08:55:15.673 T:2973264992 DEBUG: OpenBundle - Opened bundle /home/pi/.kodi/addons/skin.estuary.mod/media/Textures.xbt
5262019-12-06 08:55:15.674 T:2973264992 INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
5272019-12-06 08:55:15.676 T:2973264992 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
5282019-12-06 08:55:15.678 T:2973264992 INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
5292019-12-06 08:55:15.680 T:2973264992 INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
5302019-12-06 08:55:15.681 T:2973264992 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
5312019-12-06 08:55:15.683 T:2973264992 INFO: Loading resource://resource.uisounds.kodi/sounds.xml
5322019-12-06 08:55:15.735 T:2973264992 INFO: skin loaded...
5332019-12-06 08:55:15.735 T:2973264992 DEBUG: Activating window ID: 12997
5342019-12-06 08:55:15.735 T:2973264992 DEBUG: ------ Window Init () ------
5352019-12-06 08:55:15.735 T:2973264992 DEBUG: Activating window ID: 12999
5362019-12-06 08:55:15.735 T:2973264992 DEBUG: ------ Window Init (Startup.xml) ------
5372019-12-06 08:55:15.735 T:2973264992 INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
5382019-12-06 08:55:15.736 T:2973264992 DEBUG: Activating window ID: 10000
5392019-12-06 08:55:15.736 T:2973264992 DEBUG: ------ Window Deinit (Startup.xml) ------
5402019-12-06 08:55:15.736 T:2973264992 DEBUG: ------ Window Init (Home.xml) ------
5412019-12-06 08:55:15.736 T:2973264992 INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
5422019-12-06 08:55:15.893 T:2973264992 DEBUG: CDirectoryProvider[pvr://channels/tv/*?view=lastplayed]: refreshing..
5432019-12-06 08:55:15.894 T:2973264992 DEBUG: CDirectoryProvider[pvr://recordings/tv/active?view=flat]: refreshing..
5442019-12-06 08:55:15.894 T:2759610576 DEBUG: Thread JobWorker start, auto delete: true
5452019-12-06 08:55:15.894 T:2973264992 DEBUG: CDirectoryProvider[plugin://script.skin.helper.widgets/?action=next&mediatype=episodes&reload=]: refreshing..
5462019-12-06 08:55:15.894 T:2973264992 DEBUG: CDirectoryProvider[videodb://inprogresstvshows]: refreshing..
5472019-12-06 08:55:15.894 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_episodes.xsp]: refreshing..
5482019-12-06 08:55:15.895 T:2751217872 DEBUG: Thread JobWorker start, auto delete: true
5492019-12-06 08:55:15.895 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_tvshows.xsp]: refreshing..
5502019-12-06 08:55:15.895 T:2973264992 DEBUG: CDirectoryProvider[videodb://tvshows/genres/]: refreshing..
5512019-12-06 08:55:15.895 T:2973264992 DEBUG: CDirectoryProvider[videodb://tvshows/studios/]: refreshing..
5522019-12-06 08:55:15.895 T:2973264992 DEBUG: CDirectoryProvider[library://video/tvshows/]: refreshing..
5532019-12-06 08:55:15.895 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/inprogress_movies.xsp]: refreshing..
5542019-12-06 08:55:15.895 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_movies.xsp]: refreshing..
5552019-12-06 08:55:15.895 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_movies.xsp]: refreshing..
5562019-12-06 08:55:15.895 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/random_movies.xsp]: refreshing..
5572019-12-06 08:55:15.895 T:2973264992 DEBUG: CDirectoryProvider[videodb://movies/genres/]: refreshing..
5582019-12-06 08:55:15.895 T:2973264992 DEBUG: CDirectoryProvider[videodb://movies/sets/]: refreshing..
5592019-12-06 08:55:15.895 T:2973264992 DEBUG: CDirectoryProvider[library://video/movies/]: refreshing..
5602019-12-06 08:55:15.895 T:2759610576 DEBUG: StartScript - calling plugin Skin Helper Service Widgets('plugin://script.skin.helper.widgets/','1','?action=next&mediatype=episodes&reload=','resume:false')
5612019-12-06 08:55:15.896 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing..
5622019-12-06 08:55:15.896 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing..
5632019-12-06 08:55:15.896 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/game/]: refreshing..
5642019-12-06 08:55:15.896 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing..
5652019-12-06 08:55:15.896 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing..
5662019-12-06 08:55:15.896 T:2973264992 DEBUG: CDirectoryProvider[addons://]: refreshing..
5672019-12-06 08:55:15.896 T:2973264992 DEBUG: CDirectoryProvider[library://video/]: refreshing..
5682019-12-06 08:55:15.896 T:2973264992 DEBUG: CDirectoryProvider[sources://video/]: refreshing..
5692019-12-06 08:55:15.896 T:2973264992 DEBUG: CDirectoryProvider[special://videoplaylists/]: refreshing..
5702019-12-06 08:55:15.897 T:2973264992 DEBUG: CDirectoryProvider[pvr://channels/radio/*?view=lastplayed]: refreshing..
5712019-12-06 08:55:15.897 T:2973264992 DEBUG: CDirectoryProvider[pvr://recordings/radio/active?view=flat]: refreshing..
5722019-12-06 08:55:15.897 T:2973264992 DEBUG: CDirectoryProvider[favourites://]: refreshing..
5732019-12-06 08:55:15.897 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/game/]: refreshing..
5742019-12-06 08:55:15.898 T:2742825168 DEBUG: Thread LanguageInvoker start, auto delete: false
5752019-12-06 08:55:15.898 T:2742825168 INFO: initializing python engine.
5762019-12-06 08:55:15.928 T:2973264992 DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
5772019-12-06 08:55:15.928 T:2973264992 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
5782019-12-06 08:55:15.928 T:2973264992 INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
5792019-12-06 08:55:15.928 T:2973264992 INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
5802019-12-06 08:55:15.962 T:2973264992 INFO: JSONRPC v10.3.0: Successfully initialized
5812019-12-06 08:55:15.964 T:2973264992 DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
5822019-12-06 08:55:15.964 T:2973264992 DEBUG: LoadMappings - loaded node "CEC Adapter"
5832019-12-06 08:55:15.964 T:2973264992 DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
5842019-12-06 08:55:15.964 T:2973264992 DEBUG: LoadMappings - loaded node "iMON HID device"
5852019-12-06 08:55:15.964 T:2973264992 DEBUG: LoadMappings - loaded node "WETEK Play remote"
5862019-12-06 08:55:15.964 T:2973264992 DEBUG: LoadMappings - loaded node ""
5872019-12-06 08:55:15.965 T:2973264992 DEBUG: CPeripheralBusUSB - initialised udev monitor
5882019-12-06 08:55:15.966 T:2726039760 DEBUG: Thread PeripBusUSBUdev start, auto delete: false
5892019-12-06 08:55:15.972 T:2717647056 DEBUG: Thread PeripBusCEC start, auto delete: false
5902019-12-06 08:55:15.977 T:2709254352 DEBUG: Thread PeripBusAddon start, auto delete: false
5912019-12-06 08:55:15.981 T:2973264992 DEBUG: Initialise - initialised peripheral on 'keyboard' with 1 features and 0 sub devices
5922019-12-06 08:55:15.982 T:2973264992 NOTICE: Register - new keyboard device registered on application->keyboard: Keyboard (0000:0000)
5932019-12-06 08:55:15.983 T:2973264992 DEBUG: Initialise - initialised peripheral on 'mouse' with 1 features and 0 sub devices
5942019-12-06 08:55:15.983 T:2973264992 NOTICE: Register - new mouse device registered on application->mouse: Mouse (0000:0000)
5952019-12-06 08:55:15.986 T:2973264992 DEBUG: ContextMenuManager: addon menus reloaded.
5962019-12-06 08:55:15.986 T:2973264992 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
5972019-12-06 08:55:15.986 T:2700861648 DEBUG: Thread PeripEventScanner start, auto delete: false
5982019-12-06 08:55:15.987 T:2973264992 DEBUG: CPlayerCoreConfig::<ctor>: created player VideoPlayer
5992019-12-06 08:55:15.988 T:2973264992 DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer
6002019-12-06 08:55:15.988 T:2973264992 DEBUG: CPlayerCoreConfig::<ctor>: created player RetroPlayer
6012019-12-06 08:55:15.988 T:2973264992 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
6022019-12-06 08:55:15.988 T:2973264992 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mms/udp
6032019-12-06 08:55:15.988 T:2973264992 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
6042019-12-06 08:55:15.988 T:2973264992 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
6052019-12-06 08:55:15.988 T:2973264992 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
6062019-12-06 08:55:15.988 T:2973264992 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
6072019-12-06 08:55:15.988 T:2973264992 DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
6082019-12-06 08:55:15.988 T:2973264992 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
6092019-12-06 08:55:15.988 T:2973264992 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
6102019-12-06 08:55:15.988 T:2973264992 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
6112019-12-06 08:55:15.988 T:2973264992 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
6122019-12-06 08:55:15.988 T:2973264992 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
6132019-12-06 08:55:15.988 T:2973264992 DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
6142019-12-06 08:55:15.988 T:2973264992 NOTICE: Loaded playercorefactory configuration
6152019-12-06 08:55:15.988 T:2973264992 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
6162019-12-06 08:55:15.989 T:2973264992 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
6172019-12-06 08:55:15.989 T:2973264992 INFO: removing tempfiles
6182019-12-06 08:55:15.996 T:2973264992 DEBUG: CRepositoryUpdater: previous update at 12/05/2019 3:55:19 PM, next at 12/06/2019 3:55:19 PM
6192019-12-06 08:55:15.999 T:2973264992 DEBUG: CServiceAddonManager: starting service.xbmc.versioncheck
6202019-12-06 08:55:15.999 T:2692468944 DEBUG: Thread Timer start, auto delete: false
6212019-12-06 08:55:16.000 T:2973264992 DEBUG: CServiceAddonManager: starting script.skin.helper.widgets
6222019-12-06 08:55:16.000 T:2684076240 DEBUG: Thread LanguageInvoker start, auto delete: false
6232019-12-06 08:55:16.001 T:2973264992 NOTICE: initialize done
6242019-12-06 08:55:16.001 T:2973264992 NOTICE: Running the application...
6252019-12-06 08:55:16.002 T:2973264992 DEBUG: no profile autoexec.py (/home/pi/.kodi/userdata/autoexec.py) found, skipping
6262019-12-06 08:55:16.002 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
6272019-12-06 08:55:16.002 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
6282019-12-06 08:55:16.002 T:2973264992 DEBUG: NetworkMessage - Starting network services
6292019-12-06 08:55:16.003 T:2684076240 INFO: initializing python engine.
6302019-12-06 08:55:16.003 T:2675683536 DEBUG: Thread LanguageInvoker start, auto delete: false
6312019-12-06 08:55:16.003 T:2675683536 INFO: initializing python engine.
6322019-12-06 08:55:16.007 T:2973264992 DEBUG: CZeroconfAvahi::clientCallback: client is up and running
6332019-12-06 08:55:16.007 T:2973264992 NOTICE: starting zeroconf publishing
6342019-12-06 08:55:16.008 T:2973264992 INFO: JSONRPC Server: Successfully initialized
6352019-12-06 08:55:16.012 T:2657083600 DEBUG: Thread EventServer start, auto delete: false
6362019-12-06 08:55:16.012 T:2657083600 NOTICE: ES: Starting UDP Event server on port 9777
6372019-12-06 08:55:16.012 T:2657083600 NOTICE: UDP: Listening on port 9777 (ipv6 : false)
6382019-12-06 08:55:16.012 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers(/var/run/lirc/lircd)
6392019-12-06 08:55:16.012 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
6402019-12-06 08:55:16.012 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
6412019-12-06 08:55:16.012 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
6422019-12-06 08:55:16.012 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
6432019-12-06 08:55:16.012 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
6442019-12-06 08:55:16.012 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
6452019-12-06 08:55:16.013 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
6462019-12-06 08:55:16.013 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
6472019-12-06 08:55:16.013 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
6482019-12-06 08:55:16.013 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
6492019-12-06 08:55:16.013 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
6502019-12-06 08:55:16.013 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
6512019-12-06 08:55:16.013 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
6522019-12-06 08:55:16.013 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
6532019-12-06 08:55:16.013 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
6542019-12-06 08:55:16.013 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
6552019-12-06 08:55:16.013 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
6562019-12-06 08:55:16.013 T:2648690896 DEBUG: Thread TCPServer start, auto delete: false
6572019-12-06 08:55:16.016 T:2973264992 DEBUG: CMMALPool::CMMALPool Created pool 0x494a540 of size 26 x 0 for port vc.ril.video_decode:out:0(I420)
6582019-12-06 08:55:16.021 T:2973264992 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
6592019-12-06 08:55:16.021 T:2973264992 NOTICE: VideoPlayer::OpenFile: /var/run/lirc/lircd
6602019-12-06 08:55:16.021 T:2973264992 DEBUG: CMMALRenderer::CMMALRenderer
6612019-12-06 08:55:16.022 T:2973264992 DEBUG: OnPlayBackStarted: CApplication::OnPlayBackStarted
6622019-12-06 08:55:16.022 T:2622275792 DEBUG: Thread VideoPlayer start, auto delete: false
6632019-12-06 08:55:16.024 T:2622275792 NOTICE: Creating InputStream
6642019-12-06 08:55:16.024 T:2622275792 ERROR: CVideoPlayer::OpenInputStream - error opening [/var/run/lirc/lircd]
6652019-12-06 08:55:16.024 T:2622275792 NOTICE: CVideoPlayer::OnExit()
6662019-12-06 08:55:16.024 T:2622275792 DEBUG: Thread VideoPlayer 2622275792 terminating
6672019-12-06 08:55:16.024 T:2630668496 DEBUG: Thread MMALProcess start, auto delete: false
6682019-12-06 08:55:16.024 T:2630668496 DEBUG: CMMALRenderer::Run - starting
6692019-12-06 08:55:16.025 T:2613883088 DEBUG: Thread JobWorker start, auto delete: true
6702019-12-06 08:55:16.045 T:2613883088 DEBUG: Loading settings for /var/run/lirc/lircd
6712019-12-06 08:55:16.053 T:2613883088 DEBUG: OnPlayBackStopped: CApplication::OnPlayBackStopped
6722019-12-06 08:55:16.053 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
6732019-12-06 08:55:16.053 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
6742019-12-06 08:55:16.304 T:2684076240 DEBUG: CPythonInvoker(1, /home/pi/.kodi/addons/service.xbmc.versioncheck/service.py): start processing
6752019-12-06 08:55:16.307 T:2742825168 DEBUG: CPythonInvoker(0, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): start processing
6762019-12-06 08:55:16.319 T:2675683536 DEBUG: CPythonInvoker(2, /home/pi/.kodi/addons/script.skin.helper.widgets/service.py): start processing
6772019-12-06 08:55:16.634 T:2973264992 DEBUG: CDirectoryProvider[pvr://channels/tv/*?view=lastplayed]: refreshing..
6782019-12-06 08:55:16.836 T:2973264992 DEBUG: ------ Window Init () ------
6792019-12-06 08:55:16.839 T:2973264992 DEBUG: ------ Window Init (Pointer.xml) ------
6802019-12-06 08:55:17.040 T:2684076240 DEBUG: -->Python Interpreter Initialized<--
6812019-12-06 08:55:17.040 T:2684076240 DEBUG: CPythonInvoker(1, /home/pi/.kodi/addons/service.xbmc.versioncheck/service.py): the source file to load is "/home/pi/.kodi/addons/service.xbmc.versioncheck/service.py"
6822019-12-06 08:55:17.040 T:2684076240 DEBUG: CPythonInvoker(1, /home/pi/.kodi/addons/service.xbmc.versioncheck/service.py): setting the Python path to /home/pi/.kodi/addons/service.xbmc.versioncheck:/usr/lib/python2.7:/usr/lib/python2.7/plat-arm-linux-gnueabihf:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages
6832019-12-06 08:55:17.040 T:2684076240 DEBUG: CPythonInvoker(1, /home/pi/.kodi/addons/service.xbmc.versioncheck/service.py): entering source directory /home/pi/.kodi/addons/service.xbmc.versioncheck
6842019-12-06 08:55:17.041 T:2684076240 DEBUG: CPythonInvoker(1, /home/pi/.kodi/addons/service.xbmc.versioncheck/service.py): instantiating addon using automatically obtained id of "service.xbmc.versioncheck" dependent on version 2.14.0 of the xbmc.python api
6852019-12-06 08:55:39.173 T:2717647056 DEBUG: GetMappingForDevice - device (2708:1001) mapped to CEC Adapter (type = cec)
6862019-12-06 08:55:39.185 T:2717647056 DEBUG: InitialiseFeature - using libCEC v4.0.4
6872019-12-06 08:55:39.186 T:2717647056 DEBUG: Initialise - initialised peripheral on 'RPI' with 2 features and 0 sub devices
6882019-12-06 08:55:39.186 T:2717647056 NOTICE: Register - new cec device registered on cec->RPI: CEC Adapter (2708:1001)
6892019-12-06 08:55:39.186 T:2717647056 DEBUG: Thread PeripBusCEC 2717647056 terminating
6902019-12-06 08:55:39.188 T:2553278672 DEBUG: Thread CECAdapter start, auto delete: false
6912019-12-06 08:55:39.189 T:2553278672 DEBUG: OpenConnection - opening a connection to the CEC adapter: RPI
6922019-12-06 08:55:39.214 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnChanged from xbmc
6932019-12-06 08:55:39.215 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1024, from xbmc, message OnChanged
6942019-12-06 08:55:39.215 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
6952019-12-06 08:55:39.215 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
6962019-12-06 08:55:39.217 T:2742825168 DEBUG: -->Python Interpreter Initialized<--
6972019-12-06 08:55:39.217 T:2742825168 DEBUG: CPythonInvoker(0, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): the source file to load is "/home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py"
6982019-12-06 08:55:39.219 T:2675683536 DEBUG: -->Python Interpreter Initialized<--
6992019-12-06 08:55:39.219 T:2675683536 DEBUG: CPythonInvoker(2, /home/pi/.kodi/addons/script.skin.helper.widgets/service.py): the source file to load is "/home/pi/.kodi/addons/script.skin.helper.widgets/service.py"
7002019-12-06 08:55:39.221 T:2675683536 DEBUG: CPythonInvoker(2, /home/pi/.kodi/addons/script.skin.helper.widgets/service.py): setting the Python path to /home/pi/.kodi/addons/script.skin.helper.widgets:/home/pi/.kodi/addons/script.module.arrow/lib:/home/pi/.kodi/addons/script.module.beautifulsoup/lib:/home/pi/.kodi/addons/script.module.certifi/lib:/home/pi/.kodi/addons/script.module.chardet/lib:/home/pi/.kodi/addons/script.module.dateutil/lib:/home/pi/.kodi/addons/script.module.idna/lib:/home/pi/.kodi/addons/script.module.metadatautils/lib:/home/pi/.kodi/addons/script.module.musicbrainz/lib:/home/pi/.kodi/addons/script.module.requests/lib:/home/pi/.kodi/addons/script.module.simplecache/lib:/home/pi/.kodi/addons/script.module.simplejson/lib:/home/pi/.kodi/addons/script.module.six/lib:/home/pi/.kodi/addons/script.module.thetvdb/lib:/home/pi/.kodi/addons/script.module.urllib3/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-arm-linux-gnueabihf:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages
7012019-12-06 08:55:39.221 T:2675683536 DEBUG: CPythonInvoker(2, /home/pi/.kodi/addons/script.skin.helper.widgets/service.py): entering source directory /home/pi/.kodi/addons/script.skin.helper.widgets
7022019-12-06 08:55:39.221 T:2675683536 DEBUG: CPythonInvoker(2, /home/pi/.kodi/addons/script.skin.helper.widgets/service.py): instantiating addon using automatically obtained id of "script.skin.helper.widgets" dependent on version 2.24.0 of the xbmc.python api
7032019-12-06 08:55:39.222 T:2742825168 DEBUG: CPythonInvoker(0, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): setting the Python path to /home/pi/.kodi/addons/script.skin.helper.widgets:/home/pi/.kodi/addons/script.module.arrow/lib:/home/pi/.kodi/addons/script.module.beautifulsoup/lib:/home/pi/.kodi/addons/script.module.certifi/lib:/home/pi/.kodi/addons/script.module.chardet/lib:/home/pi/.kodi/addons/script.module.dateutil/lib:/home/pi/.kodi/addons/script.module.idna/lib:/home/pi/.kodi/addons/script.module.metadatautils/lib:/home/pi/.kodi/addons/script.module.musicbrainz/lib:/home/pi/.kodi/addons/script.module.requests/lib:/home/pi/.kodi/addons/script.module.simplecache/lib:/home/pi/.kodi/addons/script.module.simplejson/lib:/home/pi/.kodi/addons/script.module.six/lib:/home/pi/.kodi/addons/script.module.thetvdb/lib:/home/pi/.kodi/addons/script.module.urllib3/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-arm-linux-gnueabihf:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages
7042019-12-06 08:55:39.222 T:2742825168 DEBUG: CPythonInvoker(0, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): entering source directory /home/pi/.kodi/addons/script.skin.helper.widgets
7052019-12-06 08:55:39.222 T:2742825168 DEBUG: CPythonInvoker(0, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): instantiating addon using automatically obtained id of "script.skin.helper.widgets" dependent on version 2.24.0 of the xbmc.python api
7062019-12-06 08:55:39.307 T:2973264992 DEBUG: ------ Window Init (DialogBusy.xml) ------
7072019-12-06 08:55:39.308 T:2973264992 NOTICE: CVideoPlayer::CloseFile()
7082019-12-06 08:55:39.308 T:2973264992 DEBUG: DeleteRenderer - deleting renderer
7092019-12-06 08:55:39.308 T:2973264992 DEBUG: CMMALRenderer::~CMMALRenderer
7102019-12-06 08:55:39.308 T:2973264992 DEBUG: CMMALRenderer::UnInitMMAL
7112019-12-06 08:55:39.313 T:2630668496 DEBUG: CMMALRenderer::Run - stopping
7122019-12-06 08:55:39.313 T:2630668496 DEBUG: Thread MMALProcess 2630668496 terminating
7132019-12-06 08:55:39.315 T:2973264992 NOTICE: VideoPlayer: waiting for threads to exit
7142019-12-06 08:55:39.315 T:2973264992 NOTICE: VideoPlayer: finished waiting
7152019-12-06 08:55:39.317 T:2973264992 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
7162019-12-06 08:55:39.317 T:2973264992 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x494a540 for port vc.ril.video_decode:out:0(I420)
7172019-12-06 08:55:39.320 T:2973264992 DEBUG: LogindUPowerSyscall - Received unknown signal NameAcquired
7182019-12-06 08:55:39.321 T:2973264992 DEBUG: ------ Window Init (DialogNotification.xml) ------
7192019-12-06 08:55:39.322 T:2973264992 DEBUG: CDirectoryProvider[pvr://channels/tv/*?view=lastplayed]: refreshing..
7202019-12-06 08:55:39.326 T:2973264992 DEBUG: ------ Window Deinit (Pointer.xml) ------
7212019-12-06 08:55:39.380 T:2768003280 DEBUG: CAddonMgr::GetAvailableUpdates took 48 ms
7222019-12-06 08:55:39.386 T:2973264992 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
7232019-12-06 08:55:39.413 T:2768003280 DEBUG: NFS: Context for 192.168.1.16/media/2TB not open - get a new context.
7242019-12-06 08:55:39.462 T:2768003280 DEBUG: NFS: Connected to server 192.168.1.16 and export /media/2TB
7252019-12-06 08:55:39.462 T:2768003280 DEBUG: NFS: chunks: r/w 65536/65536
7262019-12-06 08:55:39.463 T:2751217872 DEBUG: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
7272019-12-06 08:55:39.463 T:2751217872 DEBUG: Getting Directory (special://profile/playlists/video)
7282019-12-06 08:55:39.464 T:2751217872 DEBUG: Getting Directory (special://profile/playlists/mixed)
7292019-12-06 08:55:39.465 T:2751217872 DEBUG: CMultiPathDirectory::MergeItems, items = 0
7302019-12-06 08:55:39.473 T:2751217872 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
7312019-12-06 08:55:39.481 T:2751217872 DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.*, songartistview.* FROM (SELECT idAlbum FROM album ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum = recentalbums.idAlbum JOIN songartistview ON songview.idSong = songartistview.idSong ORDER BY songview.idAlbum DESC, songview.idSong, songartistview.idRole, songartistview.iOrder
7322019-12-06 08:55:39.483 T:2751217872 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
7332019-12-06 08:55:39.486 T:2751217872 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
7342019-12-06 08:55:39.495 T:2675683536 NOTICE: Skin Helper Widgets --> Backgroundservice started
7352019-12-06 08:55:39.567 T:2768003280 DEBUG: NFS: Context for 192.168.1.16/media/3TB not open - get a new context.
7362019-12-06 08:55:39.600 T:2768003280 DEBUG: NFS: Connected to server 192.168.1.16 and export /media/3TB
7372019-12-06 08:55:39.600 T:2768003280 DEBUG: NFS: chunks: r/w 65536/65536
7382019-12-06 08:55:39.656 T:2768003280 DEBUG: NFS: Context for 192.168.1.16/media/4TB not open - get a new context.
7392019-12-06 08:55:39.665 T:2768003280 DEBUG: NFS: Connected to server 192.168.1.16 and export /media/4TB
7402019-12-06 08:55:39.665 T:2768003280 DEBUG: NFS: chunks: r/w 65536/65536
7412019-12-06 08:55:39.696 T:2768003280 DEBUG: NFS: Refreshing context for 192.168.1.16/media/2TB, old: 6527, new: 6810
7422019-12-06 08:55:39.696 T:2768003280 DEBUG: NFS: Using cached context.
7432019-12-06 08:55:39.698 T:2768003280 DEBUG: NFS: Refreshing context for 192.168.1.16/media/3TB, old: 6681, new: 6813
7442019-12-06 08:55:39.699 T:2768003280 DEBUG: NFS: Using cached context.
7452019-12-06 08:55:39.701 T:2768003280 DEBUG: NFS: Refreshing context for 192.168.1.16/media/4TB, old: 6771, new: 6815
7462019-12-06 08:55:39.701 T:2768003280 DEBUG: NFS: Using cached context.
7472019-12-06 08:55:39.715 T:2768003280 DEBUG: ADDON: Dll Initializing - Tvheadend HTSP Client
7482019-12-06 08:55:39.715 T:2768003280 DEBUG: SECTION:LoadDLL(/usr/lib/arm-linux-gnueabihf/kodi/addons/pvr.hts/pvr.hts.so.4.4.18)
7492019-12-06 08:55:39.715 T:2768003280 DEBUG: Loading: /usr/lib/arm-linux-gnueabihf/kodi/addons/pvr.hts/pvr.hts.so.4.4.18
7502019-12-06 08:55:39.726 T:2768003280 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - starting PVR client
7512019-12-06 08:55:39.732 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'host'
7522019-12-06 08:55:39.733 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
7532019-12-06 08:55:39.733 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
7542019-12-06 08:55:39.736 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
7552019-12-06 08:55:39.736 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'htsp_port'
7562019-12-06 08:55:39.737 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
7572019-12-06 08:55:39.737 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
7582019-12-06 08:55:39.738 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
7592019-12-06 08:55:39.739 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'http_port'
7602019-12-06 08:55:39.740 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
7612019-12-06 08:55:39.740 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
7622019-12-06 08:55:39.741 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
7632019-12-06 08:55:39.741 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'user'
7642019-12-06 08:55:39.742 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
7652019-12-06 08:55:39.742 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
7662019-12-06 08:55:39.743 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
7672019-12-06 08:55:39.743 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'pass'
7682019-12-06 08:55:39.744 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
7692019-12-06 08:55:39.744 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
7702019-12-06 08:55:39.745 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
7712019-12-06 08:55:39.745 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'connect_timeout'
7722019-12-06 08:55:39.746 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
7732019-12-06 08:55:39.746 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
7742019-12-06 08:55:39.753 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
7752019-12-06 08:55:39.753 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'response_timeout'
7762019-12-06 08:55:39.754 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
7772019-12-06 08:55:39.754 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
7782019-12-06 08:55:39.755 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
7792019-12-06 08:55:39.756 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'trace_debug'
7802019-12-06 08:55:39.757 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
7812019-12-06 08:55:39.757 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
7822019-12-06 08:55:39.758 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
7832019-12-06 08:55:39.758 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'epg_async'
7842019-12-06 08:55:39.759 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
7852019-12-06 08:55:39.759 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
7862019-12-06 08:55:39.760 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
7872019-12-06 08:55:39.760 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'pretuner_enabled'
7882019-12-06 08:55:39.761 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
7892019-12-06 08:55:39.761 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
7902019-12-06 08:55:39.762 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
7912019-12-06 08:55:39.762 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'autorec_approxtime'
7922019-12-06 08:55:39.763 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
7932019-12-06 08:55:39.763 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
7942019-12-06 08:55:39.764 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
7952019-12-06 08:55:39.764 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'autorec_maxdiff'
7962019-12-06 08:55:39.765 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
7972019-12-06 08:55:39.765 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
7982019-12-06 08:55:39.765 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
7992019-12-06 08:55:39.766 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'streaming_profile'
8002019-12-06 08:55:39.773 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
8012019-12-06 08:55:39.773 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
8022019-12-06 08:55:39.774 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
8032019-12-06 08:55:39.775 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'dvr_priority'
8042019-12-06 08:55:39.775 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
8052019-12-06 08:55:39.775 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
8062019-12-06 08:55:39.776 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
8072019-12-06 08:55:39.777 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'dvr_lifetime'
8082019-12-06 08:55:39.777 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
8092019-12-06 08:55:39.777 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
8102019-12-06 08:55:39.778 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
8112019-12-06 08:55:39.779 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'dvr_dubdetect'
8122019-12-06 08:55:39.780 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
8132019-12-06 08:55:39.780 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
8142019-12-06 08:55:39.781 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
8152019-12-06 08:55:39.781 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'dvr_playstatus'
8162019-12-06 08:55:39.782 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
8172019-12-06 08:55:39.782 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
8182019-12-06 08:55:39.783 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
8192019-12-06 08:55:39.783 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'stream_readchunksize'
8202019-12-06 08:55:39.784 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
8212019-12-06 08:55:39.784 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
8222019-12-06 08:55:39.785 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
8232019-12-06 08:55:39.785 T:2768003280 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'dvr_ignore_duplicates'
8242019-12-06 08:55:39.786 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting definitions
8252019-12-06 08:55:39.786 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: trying to load setting definitions from old format...
8262019-12-06 08:55:39.793 T:2768003280 DEBUG: CAddonSettings[pvr.hts]: loading setting values
8272019-12-06 08:55:39.802 T:2768003280 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connection state change (0 -> 7)
8282019-12-06 08:55:39.813 T:2768003280 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:Kodi (raspberrypi) port:9090
8292019-12-06 08:55:39.814 T:2768003280 DEBUG: CZeroconfAvahi::addService() named: Kodi (raspberrypi) type: _xbmc-jsonrpc._tcp port:9090
8302019-12-06 08:55:39.814 T:2630668496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - new connection requested
8312019-12-06 08:55:39.814 T:2630668496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connecting to 127.0.0.1:9982
8322019-12-06 08:55:39.815 T:2630668496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connected
8332019-12-06 08:55:39.817 T:2536493264 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - sending hello
8342019-12-06 08:55:39.820 T:2768003280 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:Kodi (raspberrypi) port:9777
8352019-12-06 08:55:39.820 T:2768003280 DEBUG: CZeroconfAvahi::addService() named: Kodi (raspberrypi) type: _xbmc-events._udp port:9777
8362019-12-06 08:55:39.904 T:2751217872 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
8372019-12-06 08:55:39.913 T:2751217872 DEBUG: GetArtistsByWhere query: SELECT COUNT(DISTINCT artistview.idArtist) FROM artistview WHERE (EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artistview.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '')
8382019-12-06 08:55:40.735 T:2665476304 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
8392019-12-06 08:55:40.884 T:2684076240 DEBUG: Previous line repeats 1 times.
8402019-12-06 08:55:40.884 T:2684076240 DEBUG: CAddonSettings[service.xbmc.versioncheck]: loading setting definitions
8412019-12-06 08:55:40.884 T:2684076240 DEBUG: CAddonSettings[service.xbmc.versioncheck]: trying to load setting definitions from old format...
8422019-12-06 08:55:40.885 T:2684076240 DEBUG: Version Check: Version 0.4.7 started
8432019-12-06 08:55:40.885 T:2742825168 DEBUG: Skin Helper Simplecache --> Initialized
8442019-12-06 08:55:40.886 T:2742825168 DEBUG: CAddonSettings[script.module.metadatautils]: loading setting definitions
8452019-12-06 08:55:40.886 T:2742825168 DEBUG: CAddonSettings[script.module.metadatautils]: trying to load setting definitions from old format...
8462019-12-06 08:55:40.888 T:2742825168 DEBUG: Skin Helper Simplecache --> Initialized
8472019-12-06 08:55:40.889 T:2742825168 DEBUG: script.module.thetvdb --> Initialized
8482019-12-06 08:55:40.889 T:2742825168 DEBUG: Skin Helper Simplecache --> Initialized
8492019-12-06 08:55:40.968 T:2742825168 DEBUG: Previous line repeats 1 times.
8502019-12-06 08:55:40.968 T:2742825168 DEBUG: CAddonSettings[script.module.metadatautils]: loading setting definitions
8512019-12-06 08:55:40.968 T:2742825168 DEBUG: CAddonSettings[script.module.metadatautils]: trying to load setting definitions from old format...
8522019-12-06 08:55:40.970 T:2742825168 DEBUG: Skin Helper Simplecache --> Initialized
8532019-12-06 08:55:40.970 T:2742825168 DEBUG: Metadata and Artwork module --> Initialized
8542019-12-06 08:55:40.970 T:2742825168 DEBUG: Skin Helper Simplecache --> Initialized
8552019-12-06 08:55:40.971 T:2742825168 DEBUG: CAddonSettings[script.skin.helper.widgets]: loading setting definitions
8562019-12-06 08:55:40.971 T:2742825168 DEBUG: CAddonSettings[script.skin.helper.widgets]: trying to load setting definitions from old format...
8572019-12-06 08:55:40.976 T:2742825168 DEBUG: Skin Helper Widgets --> MEDIATYPE: episodes - ACTION: next -- no cache, quering kodi api to get items - CHECKSUM: action.nextepisodes_enable_specials.Falsegroup_episodes.Falsehide_watched.Falselimit.25mediatype.episodesnext_inprogress_only.True
8582019-12-06 08:55:41.147 T:2526015696 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 31
8592019-12-06 08:55:41.366 T:2492444880 DEBUG: Previous line repeats 3 times.
8602019-12-06 08:55:41.366 T:2492444880 ERROR: NEWADDON Unknown Video Info Key "progress"
8612019-12-06 08:55:41.367 T:2973264992 INFO: UDisks2: Added /media/pi/rootfs
8622019-12-06 08:55:41.368 T:2517622992 ERROR: NEWADDON Unknown Video Info Key "progress"
8632019-12-06 08:55:41.440 T:2742825168 ERROR: Previous line repeats 2 times.
8642019-12-06 08:55:41.440 T:2742825168 DEBUG: Skin Helper Simplecache --> Closed
8652019-12-06 08:55:41.440 T:2742825168 DEBUG: Previous line repeats 4 times.
8662019-12-06 08:55:41.440 T:2742825168 DEBUG: Metadata and Artwork module --> Exited
8672019-12-06 08:55:41.440 T:2742825168 DEBUG: Skin Helper Simplecache --> Closed
8682019-12-06 08:55:41.440 T:2742825168 DEBUG: Skin Helper Widgets --> MainModule exited
8692019-12-06 08:55:41.440 T:2742825168 INFO: CPythonInvoker(0, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): script successfully run
8702019-12-06 08:55:41.441 T:2742825168 DEBUG: onExecutionDone(0, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py)
8712019-12-06 08:55:41.465 T:2742825168 INFO: Python interpreter stopped
8722019-12-06 08:55:41.465 T:2742825168 DEBUG: Thread LanguageInvoker 2742825168 terminating
8732019-12-06 08:55:41.584 T:2553278672 DEBUG: OpenConnection - connection to the CEC adapter opened
8742019-12-06 08:55:41.584 T:2553278672 DEBUG: SetConfigurationFromLibCEC - settings updated by libCEC
8752019-12-06 08:55:41.584 T:2742825168 DEBUG: Thread CECAdapterUpdate start, auto delete: false
8762019-12-06 08:55:41.855 T:2742825168 DEBUG: UpdateMenuLanguage - requesting the menu language of the TV
8772019-12-06 08:55:43.261 T:2742825168 DEBUG: SetMenuLanguage - language set to 'resource.language.en_gb'
8782019-12-06 08:55:44.077 T:2536493264 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connected to Tvheadend / 4.2.8-34~g24a2f59e9 (HTSPv27)
8792019-12-06 08:55:44.077 T:2536493264 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - sending auth
8802019-12-06 08:55:44.078 T:2536493264 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - Received permissions:
8812019-12-06 08:55:44.078 T:2536493264 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - administrator : 1
8822019-12-06 08:55:44.078 T:2536493264 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - HTSP streaming : 1
8832019-12-06 08:55:44.078 T:2536493264 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - HTSP DVR : 1
8842019-12-06 08:55:44.078 T:2536493264 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - Failed/aborted DVR : 1
8852019-12-06 08:55:44.078 T:2536493264 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - anonymous HTSP only : 0
8862019-12-06 08:55:44.078 T:2536493264 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - global connection limit : 0
8872019-12-06 08:55:44.078 T:2536493264 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - DVR connection limit : 0
8882019-12-06 08:55:44.078 T:2536493264 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - streaming connection limit : 0
8892019-12-06 08:55:44.078 T:2536493264 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - rebuilding state
8902019-12-06 08:55:44.078 T:2536493264 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - request async EPG (3)
8912019-12-06 08:55:44.080 T:2536493264 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - async updates requested
8922019-12-06 08:55:44.080 T:2536493264 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - registered
8932019-12-06 08:55:44.080 T:2536493264 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connection state change (7 -> 5)
8942019-12-06 08:55:44.081 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:1, name:HDTV
8952019-12-06 08:55:44.081 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:2, name:SDTV
8962019-12-06 08:55:44.081 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:115165999, name:Yesterday
8972019-12-06 08:55:44.081 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:377943941, name:BBC TWO HD
8982019-12-06 08:55:44.081 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:403220312, name:Channel 5 HD
8992019-12-06 08:55:44.081 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:405537213, name:ITV3
9002019-12-06 08:55:44.081 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:609074091, name:Sky News
9012019-12-06 08:55:44.081 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:610901011, name:BBC ONE HD
9022019-12-06 08:55:44.081 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:927838518, name:Dave
9032019-12-06 08:55:44.081 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1112090584, name:Channel 4+1
9042019-12-06 08:55:44.081 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1121896905, name:Channel 5+1
9052019-12-06 08:55:44.081 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1226357802, name:ITV4
9062019-12-06 08:55:44.081 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1255839054, name:Channel 4 HD
9072019-12-06 08:55:44.086 T:2759610576 NOTICE: PVR Manager: Starting
9082019-12-06 08:55:44.087 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1401429799, name:Channel 4
9092019-12-06 08:55:44.087 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1582536824, name:ITVBe
9102019-12-06 08:55:44.087 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1711748082, name:ITV +1
9112019-12-06 08:55:44.087 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1755930421, name:Dave ja vu
9122019-12-06 08:55:44.087 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1830834322, name:More 4
9132019-12-06 08:55:44.088 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1906726315, name:ITV HD
9142019-12-06 08:55:44.088 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:2011606173, name:E4
9152019-12-06 08:55:44.088 T:2536493264 DEBUG: Thread PVRManager start, auto delete: false
9162019-12-06 08:55:44.089 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:2119015644, name:ITV2
9172019-12-06 08:55:44.089 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:1, name:HDTV
9182019-12-06 08:55:44.089 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:2, name:SDTV
9192019-12-06 08:55:44.093 T:2526015696 DEBUG: Thread PVRGUIProgressHandler start, auto delete: true
9202019-12-06 08:55:44.093 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:250224135, state:completed, title:EastEnders, desc:Adam's lies begin to catch up with him, Denise lets Sheree know who's in charge, and Max makes a surprising discovery. [S,AD] [HD], error:none
9212019-12-06 08:55:44.095 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1818801455, state:completed, title:Seinfeld, desc:, error:none
9222019-12-06 08:55:44.095 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1803828758, state:completed, title:New: What Makes a Murderer, desc:Aged 20, Paul Aldridge was convicted of murder by joint enterprise and sentenced to life in prison. Now Paul submits himself to a series of biological and psychological tests. (Ep2/3) [AD,S], error:none
9232019-12-06 08:55:44.095 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1069522326, state:completed, title:New: I'm A Celebrity...Get Me..., desc:...Out Of Here! The jungle crown is tantalisingly close for the campmates. No one wants to leave before the final when someone will be crowned the 2018 King or Queen of the Jungle. S18 Ep21, error:none
9242019-12-06 08:55:44.095 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:375869366, state:missed, title:EastEnders, desc:The events of last week continue to hang heavy over Walford, Chantelle shines with the support of her family, and Shirley makes a tempting offer to Tina. [S,AD] [HD], error:none
9252019-12-06 08:55:44.095 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:2021349023, state:completed, title:Holby City, desc:Too Good to Be True: Fletch finally confronts Jac over their feelings for each other. Jason makes a life-changing decision. Lofty hides his true feelings about his future with Dom. [HD] [S], error:none
9262019-12-06 08:55:44.095 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:217798153, state:completed, title:EastEnders, desc:Phil struggles to contain his suspicions, Mick puts his plan into action, and Ben realises he has put Phil's business at risk. [S,AD] [HD], error:none
9272019-12-06 08:55:44.096 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1341268178, state:completed, title:8 Out of 10 Cats Does Countdown..., desc:...Christmas Special: Jimmy Carr hosts as Sean Lock and Kathy Burke take on Jon Richardson and David Mitchell. Plus James Acaster and Joe Wilkinson join Susie Dent and Rachel Riley., error:none
9282019-12-06 08:55:44.096 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:501061894, state:completed, title:Match of the Day 2, desc:Mark Chapman presents highlights of Sundays two mouthwatering fixtures from the Premier League, with Bournemouth hosting Manchester City and Tottenham facing Newcastle. [S] [HD], error:none
9292019-12-06 08:55:44.096 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:404517554, state:completed, title:8 Out of 10 Cats Does Countdown, desc:Jimmy Carr hosts the panel show, as Johnny Vegas and Rhod Gilbert take on Joe Wilkinson and Roisin Conaty. Tom Allen is in Dictionary Corner. (S15 Ep4/4) [S], error:none
9302019-12-06 08:55:44.096 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:67482620, state:completed, title:EastEnders, desc:Linda makes a shock announcement. Bernadette meddles in Tiffany's business. Sharon makes a big decision. [HD] [AD,S], error:none
9312019-12-06 08:55:44.096 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:331775203, state:completed, title:EastEnders, desc:Tiffany is left reeling from the consequences of Jacks recent actions, and Phil makes a drastic decision about his familys future. [S,AD] [HD], error:none
9322019-12-06 08:55:44.096 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1814446570, state:completed, title:EastEnders, desc:Jack is left frustrated whilst trying to help Tiffany, Jay confides in Billy about Ruby, and Ian is on the warpath. [S,AD] [HD], error:none
9332019-12-06 08:55:44.096 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1809353022, state:completed, title:Live: The Ultimate Fighting Championship [New!], desc:All the Prelims action from Liverpool ahead of the main event between Stephen 'Wonderboy' Thompson and Darren Till., error:none
9342019-12-06 08:55:44.096 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:253850439, state:completed, title:Match of the Day, desc:All the highlights from Saturdays top-flight fixtures, including Manchester City v Tottenham, Southampton v Liverpool and Norwich v Newcastle. [S] [HD], error:none
9352019-12-06 08:55:44.096 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:697951782, state:completed, title:Seinfeld, desc:, error:none
9362019-12-06 08:55:44.097 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:609119130, state:completed, title:Friends, desc:, error:none
9372019-12-06 08:55:44.097 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:48199428, state:completed, title:8 Out of 10 Cats Does Countdown, desc:Jimmy Carr hosts, as Sean Lock and David Mitchell take on Jon Richardson and Sara Pascoe. Plus, Ellie White and Natasia Demetriou are in Dictionary Corner. (S17 Ep4), error:none
9382019-12-06 08:55:44.097 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:635227850, state:completed, title:The Great British Bake Off: An..., desc:...Extra Slice: Jo Brand is joined by guests Prue Leith, Adam Hills and Joel Dommett, as they examine whether Spice Week proved too hot to handle for the bakers. (S2 Ep5/10) [AD,S], error:none
9392019-12-06 08:55:44.097 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1666892112, state:completed, title:EastEnders, desc:Rainie stirs up trouble for Stuart, Jay is stunned by Ben, error:none
9402019-12-06 08:55:44.097 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1741313664, state:completed, title:8 Out of 10 Cats Does Countdown, desc:Jimmy Carr hosts a brand new episode, as Sean Lock and Richard Ayoade take on Jon Richardson and Jessica Knappett. Plus special guests Alex Horne and the Horne Section. (S16 Ep6) [S], error:none
9412019-12-06 08:55:44.097 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:986388151, state:completed, title:8 Out of 10 Cats Does Countdown, desc:Jimmy Carr hosts the panel show, as Jason Manford and Joe Wilkinson take on Lee Mack and Fay Ripley. Alex Horne and Horne Section are in Dictionary Corner. (S14 Ep5/5) [S], error:none
9422019-12-06 08:55:44.097 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:798869672, state:completed, title:8 Out of 10 Cats Does Countdown, desc:Hosted by Jimmy Carr. Jon Richardson and Cariad Lloyd take on guest captain Alan Carr and Kevin Bridges. With Elis James, Jon Robins, Susie Dent and Rachel Riley. (S14 Ep1/5) [S], error:none
9432019-12-06 08:55:44.097 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1538111020, state:completed, title:EastEnders, desc:Honey makes a big decision about her future, and things go from bad to worse for the Slaters. [S,AD] [HD], error:none
9442019-12-06 08:55:44.097 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:516143342, state:completed, title:New: I'm A Celebrity...Get Me..., desc:...Out Of Here! The jungle crown is tantalisingly close for the campmates. No one wants to leave before the final when someone will be crowned the 2018 King or Queen of the Jungle. S18 Ep21, error:none
9452019-12-06 08:55:44.098 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1656247662, state:scheduled, title:EastEnders, desc:Lisa is left conflicted, Martin's behaviour continues to spiral, and Leo is shocked when an unexpected visitor arrives - is he about to be exposed? [S,AD] [HD], error:none
9462019-12-06 08:55:44.098 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:397732729, state:completed, title:New: Trevor McDonald and the Killer Nurse, desc:Trevor McDonald investigates the case of serial child killer Beverley Allitt and reflects on how it was reported at the time using interviews, and expert analysis. Ep1, error:none
9472019-12-06 08:55:44.098 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1381230824, state:completed, title:They Shall Not Grow Old, desc:Peter Jackson brings to life the story of World War I by transforming century-old footage. Contains some scenes which some viewers may find upsetting. [2018] [S,AD] [HD], error:none
9482019-12-06 08:55:44.098 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:640603735, state:completed, title:The Chase, desc:Quiz show hosted by Bradley Walsh. John, Alix, Helen and Benjamin take on the Chaser in the hope of going home with thousands of pounds. [S,HD] S9 Ep152, error:none
9492019-12-06 08:55:44.098 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:465368273, state:scheduled, title:Have I Got News For You, desc:9/10. Team captains Paul Merton and Ian Hislop are joined by guest host Alexander Armstrong and panellists including Jon Richardson. [S] [HD], error:none
9502019-12-06 08:55:44.098 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1483527305, state:completed, title:I'm A Celebrity...Get Me..., desc:...Me Out Of Here! The competition is getting tougher as the celebrity campmates get closer to one of them being crowned King or Queen of the Jungle. [S,HD] S19 Ep19, error:none
9512019-12-06 08:55:44.098 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1280952851, state:completed, title:Friends, desc:, error:none
9522019-12-06 08:55:44.098 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:992165288, state:completed, title:Match of the Day 2, desc:Mark Chapman presents highlights from todays two Premier League games, in which Chelsea hosted Leicester City and Crystal Palace visited newly promoted Sheffield United. [S] [HD], error:none
9532019-12-06 08:55:44.098 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1447171367, state:scheduled, title:Have I Got News For You, desc:8/10. Team captains Paul Merton and Ian Hislop are joined by guest host Stephen Mangan and panellists Roisin Conaty and Brian Cox. [S] [HD], error:none
9542019-12-06 08:55:44.099 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:249802612, state:completed, title:8 Out of 10 Cats Does Countdown, desc:Jimmy Carr hosts. Alan Carr and Josh Widdicombe take on Joe Wilkinson and Sara Pascoe. Bill Bailey is in Dictionary Corner with Susie Dent, and Rachel Riley. (S15 Ep3/4) [S], error:none
9552019-12-06 08:55:44.099 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1940603422, state:scheduled, title:EastEnders, desc:Whitney finds herself in a dangerous situation, Phil's suspicions are piqued, and Ruby takes matters into her own hands. [S,AD] [HD], error:none
9562019-12-06 08:55:44.099 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1567635488, state:completed, title:Game of the Week, desc:Our Game of the Week is the epic Bundesliga clash between Hannover and Mainz at the Niedersachsenstadion. Mainz were looking to their woeful 15-match winless run on the road., error:none
9572019-12-06 08:55:44.099 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1333221758, state:completed, title:EastEnders, desc:Ben stirs up trouble for his family, chaos descends on the Slaters, and Whitney turns to Stuart for advice about Callum. [S,AD] [HD], error:none
9582019-12-06 08:55:44.099 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1265008880, state:scheduled, title:I'm A Celebrity...Get..., desc:...Me Out Of Here! Soon, one of the celebrity campmates will get their hands on the jungle crown... but who will leave before the grand final? [S,HD] S19 Ep21, error:none
9592019-12-06 08:55:44.099 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:758452335, state:completed, title:Breakfast, desc:The latest news, sport, business and weather from the BBC's Breakfast team. [HD] [S] Including regional news at 25 and 55 minutes past each hour., error:none
9602019-12-06 08:55:44.100 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:426408460, state:completed, title:8 Out of 10 Cats Does Countdown, desc:Katherine Ryan hosts a special celebrating 100 years of women's suffrage. Roisin Conaty and Jessica Hynes v Sara Pascoe and Lolly Adefope. With Morgana Robinson, Susie and Rachel. (S16 Ep1), error:none
9612019-12-06 08:55:44.100 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1886620210, state:completed, title:The Great British Bake Off, desc:It's the semi-final. The remaining bakers are just three patisserie challenges away from the biggest day in their baking lives. But the heat is really on now. (S2 Ep9/10), error:none
9622019-12-06 08:55:44.100 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:973421998, state:completed, title:Breakfast, desc:The latest news, sport, business and weather from the BBC's Breakfast team. [HD] [S] Including Reporting Scotland at 25 and 55 minutes past each hour., error:none
9632019-12-06 08:55:44.100 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1881270774, state:completed, title:8 Out of 10 Cats Does Countdown, desc:Jimmy Carr hosts. Jon Richardson and Joe Wilkinson take on guest captain Kevin Bridges and Jessica Knappett. With Dr John Cooper Clarke in Dictionary Corner. (S15 Ep1/4) [S], error:none
9642019-12-06 08:55:44.100 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:558349985, state:completed, title:MasterChef, desc:7/24. Seven more hopefuls pull out all the stops to prove to the judges they have what it takes as the third week of heats gets under way. [S,AD], error:none
9652019-12-06 08:55:44.100 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1258278160, state:completed, title:Big Fat Quiz of the Year 2018, desc:Jimmy Carr is joined by Richard Ayoade, Noel Fielding, Mo Gilligan, David Mitchell, Claudia Winkleman and Michelle Wolf to battle it out to see who can remember most about the past year., error:none
9662019-12-06 08:55:44.100 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:972513560, state:completed, title:The Satanic Verses: 30 Years On, desc:Mobeen Azhar examines the lasting effect Salman Rushdie's novel The Satanic Verses has had on the Muslim community and how the events of 1989 continue to have an impact today. [S,AD] [HD], error:none
9672019-12-06 08:55:44.101 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:680012356, state:scheduled, title:I'm A Celebrity...Get..., desc:...Me Out Of Here! The competition is getting tougher as the celebrity campmates get closer to one of them being crowned King or Queen of the Jungle. [S,HD] S19 Ep20, error:none
9682019-12-06 08:55:44.101 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1797157458, state:completed, title:Friends, desc:, error:none
9692019-12-06 08:55:44.101 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:161187732, state:scheduled, title:EastEnders, desc:Ben makes a drastic move, the pressure mounts on Sharon, and Mick's concerns grow for Linda. [S,AD] [HD], error:none
9702019-12-06 08:55:44.101 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:210016412, state:completed, title:Ian King Live, desc:Ian King tackles the biggest business and economics stories live from Sky's studios., error:none
9712019-12-06 08:55:44.101 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1132683955, state:completed, title:Kylie at Glastonbury 2019, desc:Lauren Laverne and Clara Amfo introduce an epic hit-filled set from evergreen pop royalty Kylie Minogue. [S] [HD], error:none
9722019-12-06 08:55:44.101 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1245102509, state:completed, title:Have I Got News for You, desc:1/10. Team captains Paul Merton and Ian Hislop are joined by guest host Alexander Armstrong and panellists Naga Munchetty and Josh Widdicombe. Contains some strong language. [S] [HD], error:none
9732019-12-06 08:55:44.101 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1971145331, state:completed, title:Friends, desc:, error:none
9742019-12-06 08:55:44.101 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:2085257625, state:completed, title:8 Out of 10 Cats Does Countdown, desc:Jimmy Carr presides over Sean Lock and Stephen Mangan, who take on guest captain Richard Ayoade and Katherine Ryan. Bill Bailey is in Dictionary Corner. (S13 Ep3/3), error:none
9752019-12-06 08:55:44.101 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1747504367, state:scheduled, title:EastEnders, desc:Ben makes a worrying discovery, Ian is left humiliated, and Jay regrets a decision. [S,AD] [HD], error:none
9762019-12-06 08:55:44.101 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1186055091, state:completed, title:New: Jamie's Quick & Easy Food, desc:Jamie cooks an Asian tuna steak salad; a hearty ale barley lamb shank; a speedy sweet and sour chicken noodles; and a revamped duck and orange salad. (S3 Ep5/6), error:none
9772019-12-06 08:55:44.101 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:212564617, state:completed, title:EastEnders, desc:Jack gets more suspicions of Ray. Sharon is thrown into a panic. Tiffany finally gets what she wants. [HD] [AD,S], error:none
9782019-12-06 08:55:44.101 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1174386865, state:completed, title:8 Out of 10 Cats Does Countdown, desc:Jimmy Carr hosts the panel show, with Lee Mack and Victoria Coren Mitchell taking on Alan Carr and Dane Baptiste. Sam Simmons is in Dictionary Corner. (S15 Ep2/4) [S], error:none
9792019-12-06 08:55:44.102 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:126650498, state:completed, title:New: I'm A Celebrity...Get Me..., desc:...Out Of Here! The finishing line is in sight as the pressure continues to build in camp. Who has the strength to keep marching on to the end? S18 Ep20, error:none
9802019-12-06 08:55:44.102 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1582306624, state:completed, title:EastEnders, desc:Stacey finds herself at loggerheads with Kat and Kush, Callum takes a huge step to fix the past, and Gray and Mick find themselves in an unexpected situation. [S,AD] [HD], error:none
9812019-12-06 08:55:44.102 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1780183172, state:completed, title:Friends, desc:, error:none
9822019-12-06 08:55:44.102 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:2041326396, state:completed, title:Have I Got News For You, desc:4/9. Team captains Paul Merton and Ian Hislop are joined by guest host David Tennant and panellists Zoe Lyons and Johnny Mercer MP. Contains adult humour. [S] [HD], error:none
9832019-12-06 08:55:44.103 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:950308601, state:completed, title:New: The Great British Bake Off..., desc:...An Extra Slice: Jo Brand is joined by Bake Off fans Tez Ilyas, Hugh Dennis and Natasia Demetriou to discuss all the latest news and gossip. Tom Allen eats cake. (S3 Ep5) [AD,S], error:none
9842019-12-06 08:55:44.103 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1121936483, state:completed, title:Live: Leicester City v Tottenham, desc:Tottenham visit the King Power Stadium to face Leicester City in the Premier League. Spurs won a pulsating nine-goal thriller when the two last met on the final day of last season., error:none
9852019-12-06 08:55:44.103 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:2035718443, state:completed, title:Formula 1 Racing, desc:From Autodromo Hermanos Rodriguez in Mexico City., error:none
9862019-12-06 08:55:44.103 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:641099240, state:completed, title:The Misadventures of Romesh Ranganathan, desc:1/3. Romesh Ranganathan finds out if there is more to Haiti than the 'sensational headlines' on the news. Contains some strong language. [HD] [AD,S], error:none
9872019-12-06 08:55:44.103 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:204954536, state:completed, title:The Big Match Revisited, desc:A look back at some classic editions of The Big Match. This episode, from 31 October 1976, features West Brom v West Ham, Bristol Rovers v Charlton and Tottenham v Everton., error:none
9882019-12-06 08:55:44.103 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1319993740, state:completed, title:Seinfeld, desc:, error:none
9892019-12-06 08:55:44.103 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:370194337, state:completed, title:EastEnders, desc:Jack is left frustrated whilst trying to help Tiffany, Jay confides in Billy about Ruby, and Ian is on the warpath. [S,AD] [HD], error:none
9902019-12-06 08:55:44.103 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:536215896, state:completed, title:The Great British Bake Off, desc:It's Pastry Week, and Paul and Prue are looking for perfection - with savoury and sweet samosas, a classic French Technical, and a daunting majestic pie fit for a banquet. (S2 Ep6/10) [AD,S], error:none
9912019-12-06 08:55:44.103 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1246566699, state:completed, title:New: I'm A Celebrity...Get Me..., desc:...Out Of Here! The celebrities continue to battle the jungle and their hunger in the quest to be crowned King or Queen of the Jungle. [S,HD] S19 Ep16, error:none
9922019-12-06 08:55:44.103 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1809940833, state:completed, title:ITV News London, desc:The latest news in London and the South East, plus local weather., error:none
9932019-12-06 08:55:44.103 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1360906969, state:completed, title:Six Nations: England v France Live, desc:England take on France at Twickenham. Mark Pougatch is joined by Jonny Wilkinson and Sir Clive Woodward. With pitch-side inserts from Martin Bayfield and Dimitri Yachvili., error:none
9942019-12-06 08:55:44.104 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1214972727, state:completed, title:The Walking Dead, desc:Aaron continues searching for allies; Daryl and Rosita take action and confront an old friend., error:none
9952019-12-06 08:55:44.104 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1087528983, state:completed, title:Live: River Plate v Boca Juniors, desc:Poised at 2-2, River Plate meet Boca Juniors in the long-awaited 2nd leg of the Copa Libertadores final. The game was moved to the Bernabeu following fan violence in Buenos Aires., error:none
9962019-12-06 08:55:44.104 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:86812443, state:completed, title:8 Out of 10 Cats Does Countdown, desc:Sean Lock and Joe Wilkinson take on Jon Richardson and Sara Pascoe. Sam Simmons is in Dictionary Corner, while maths whizz Rachel Riley looks after the letters and numbers. (S18 Ep3) [S], error:none
9972019-12-06 08:55:44.104 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1478681675, state:completed, title:New: I'm A Celebrity...Get Me..., desc:...Out Of Here! The campmates are tired, hungry and homesick, but they need to keep winning stars and food for camp if they're going to make it all the way until the end. [S,HD] S19 Ep17, error:none
9982019-12-06 08:55:44.104 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1627661245, state:missed, title:The Great British Bake Off, desc:It's Bread Week and the bakers tackle a signature bake designed to be shared; a deceptively simple summer staple in the Technical; and an ambitious Showstopper. (S3 Ep3) [AD,S], error:none
9992019-12-06 08:55:44.104 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:107965204, state:missed, title:Psychopath with Piers Morgan, desc:Piers Morgan ventures behind bars to talk to diagnosed psychopath Paris Bennett about what drove him to murder his four-year-old sister. [S,AD,HD], error:none
10002019-12-06 08:55:44.105 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:2084501759, state:completed, title:Friends, desc:, error:none
10012019-12-06 08:55:44.105 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:164328445, state:completed, title:8 Out of 10 Cats Does Countdown, desc:Jimmy Carr hosts. Sean Lock and Bill Bailey take on special guest captain Joe Wilkinson with Isy Suttie. Joe Lycett is in Dictionary Corner with Susie Dent. (S7 Ep14/17) [S], error:none
10022019-12-06 08:55:44.105 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1597722804, state:completed, title:Live: Champions League Highlights, desc:All the goals from the latest set of fixtures in the UEFA Champions League, including Liverpool v Napoli, Barcelona v Tottenham and Monaco v Borussia Dortmund., error:none
10032019-12-06 08:55:44.105 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:966274534, state:completed, title:UEFA Champions League Football, desc:Five-time winners Liverpool hope to prevent Real Madrid from lifting their third consecutive UEFA Champions League title as the two lock horns in a mouth-watering final in Kiev., error:none
10042019-12-06 08:55:44.105 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1204558828, state:completed, title:Britain's Best Home Cook, desc:5/8. The six remaining home cooks must first impress the judges with the perfect chocolate pudding, before competing in a challenge using a key ingredient. [HD] [AD,S], error:none
10052019-12-06 08:55:44.105 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:54840629, state:completed, title:New: I'm a Celebrity...Get Me Out of Here! Final, desc:They've made it to the end, endured Bushtucker Trials, camp life, and each other, but which of the finalists will you crown the 2018 King or Queen of the Jungle? S18 Ep22, error:none
10062019-12-06 08:55:44.106 T:2973264992 DEBUG: ------ Window Init (DialogExtendedProgressBar.xml) ------
10072019-12-06 08:55:44.113 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:547958446, state:completed, title:8 Out of 10 Cats Does Countdown, desc:Jimmy Carr hosts. Sean Lock and Sara Pascoe take on Jon Richardson and Josh Widdicombe. Alex Horne is in Dictionary Corner with Susie Dent. (S7 Ep16/17) [S], error:none
10082019-12-06 08:55:44.113 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1063576775, state:completed, title:8 Out of 10 Cats Does Countdown, desc:Sean Lock and Katherine Ryan take on Jon Richardson and Joe Wilkinson. Adam Riches as Sean Bean is in Dictionary Corner with Susie Dent. With Rachel Riley and Jimmy Carr. (S18 Ep7) [S], error:none
10092019-12-06 08:55:44.114 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:453465901, state:scheduled, title:EastEnders, desc:Callum grows suspicious, Martin takes a risk, and Lisa makes a decision, but will it be too late? [S,AD] [HD], error:none
10102019-12-06 08:55:44.114 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:151539978, state:completed, title:8 Out of 10 Cats Does Countdown, desc:Jimmy Carr hosts the panel game. Sean Lock and Jason Manford take on Jon Richardson and Roisin Conaty, while Sam Simmons joins Susie Dent in Dictionary Corner, with Rachel Riley. (S8 Ep1/4), error:none
10112019-12-06 08:55:44.114 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1712067188, state:completed, title:Have I Got a Bit More News for You, desc:Stand-up and actress Jo Brand takes over the reins for extended edition of the satirical quiz, with broadcaster and journalist Grace Dent and comedian Kiri Pritchard-McLean joining team captains Ian Hislop and Paul Merton to poke fun at the stories making the headlines., error:none
10122019-12-06 08:55:44.114 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1470015775, state:completed, title:Friends, desc:, error:none
10132019-12-06 08:55:44.114 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:417168242, state:completed, title:Click and Collect, desc:Comedy. To save his daughter's Christmas, Andrew goes on a road trip with Dev, his neighbour from hell, to buy an elusive Sparklehoof the Unicorn Princess. [HD] [AD,S], error:none
10142019-12-06 08:55:44.114 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:206232604, state:completed, title:The Sheriffs Are Coming, desc:4/4. Rob and Gerald visit a beautiful country house in search of the money owed to a wedding planner who hasn't been paid. [S] [HD], error:none
10152019-12-06 08:55:44.114 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1153689097, state:completed, title:Have I Got News for You, desc:1/10. Team captains Paul Merton and Ian Hislop are joined by guest host Alexander Armstrong and panellists Naga Munchetty and Josh Widdicombe. Contains some strong language. [HD], error:none
10162019-12-06 08:55:44.114 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:2103874628, state:completed, title:New: I'm A Celebrity...Get Me..., desc:...Out Of Here! The end is in sight for the campmates, but there are still plenty more challenges and surprises in store for the jungle celebrities. [S,HD] S19 Ep18, error:none
10172019-12-06 08:55:44.127 T:2517622992 DEBUG: Thread PVRGUIInfo start, auto delete: false
10182019-12-06 08:55:44.127 T:2536493264 NOTICE: PVR Manager: Started
10192019-12-06 08:55:44.135 T:2500837584 DEBUG: Thread PVRGUIProgressHandler start, auto delete: true
10202019-12-06 08:55:44.135 T:2613883088 INFO: Starting PVR channel icon search
10212019-12-06 08:55:44.137 T:2509230288 DEBUG: Thread EPGUpdater start, auto delete: false
10222019-12-06 08:55:44.138 T:2768003280 NOTICE: EPG thread started
10232019-12-06 08:55:44.147 T:2973264992 DEBUG: CDirectoryProvider[pvr://channels/tv/*?view=lastplayed]: refreshing..
10242019-12-06 08:55:44.147 T:2973264992 DEBUG: CDirectoryProvider[pvr://recordings/tv/active?view=flat]: refreshing..
10252019-12-06 08:55:44.147 T:2500837584 DEBUG: Thread PVRGUIProgressHandler 2500837584 terminating (autodelete)
10262019-12-06 08:55:44.176 T:2613883088 DEBUG: ffmpeg[9BCCB0D0]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
10272019-12-06 08:55:44.209 T:2526015696 DEBUG: Thread PVRGUIProgressHandler 2526015696 terminating (autodelete)
10282019-12-06 08:55:44.231 T:2640298192 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - async updates initialised
10292019-12-06 08:55:44.233 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - profile name: webtv-vp8-vorbis-webm, comment: WEBTV profile VP8/Vorbis/WEBM added
10302019-12-06 08:55:44.233 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - profile name: webtv-h264-aac-matroska, comment: WEBTV profile H264/AAC/Matroska added
10312019-12-06 08:55:44.233 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - profile name: webtv-h264-aac-mpegts, comment: WEBTV profile H264/AAC/MPEG-TS added
10322019-12-06 08:55:44.233 T:2640298192 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - profile name: htsp, comment: HTSP Default Stream Settings added
10332019-12-06 08:55:44.248 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording I'm A Celebrity...Get Me...
10342019-12-06 08:55:44.248 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording EastEnders
10352019-12-06 08:55:44.248 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording New: I'm A Celebrity...Get Me...
10362019-12-06 08:55:44.249 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording The Sheriffs Are Coming
10372019-12-06 08:55:44.249 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording New: I'm A Celebrity...Get Me...
10382019-12-06 08:55:44.249 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording EastEnders
10392019-12-06 08:55:44.351 T:2973264992 DEBUG: ------ Window Deinit (DialogExtendedProgressBar.xml) ------
10402019-12-06 08:55:45.138 T:2526015696 DEBUG: Thread PVRGUIProgressHandler start, auto delete: true
10412019-12-06 08:55:45.139 T:2973264992 DEBUG: ------ Window Init (DialogExtendedProgressBar.xml) ------
10422019-12-06 08:55:45.143 T:2526015696 DEBUG: Thread PVRGUIProgressHandler 2526015696 terminating (autodelete)
10432019-12-06 08:55:45.254 T:2973264992 DEBUG: ------ Window Deinit (DialogExtendedProgressBar.xml) ------
10442019-12-06 08:55:45.703 T:2742825168 DEBUG: UpdateAudioSystemStatus - no CEC capable amplifier found
10452019-12-06 08:55:45.955 T:2684076240 DEBUG: Version Check: Version installed {u'major': 18, u'tag': u'stable', u'minor': 4, u'revision': u'20191027-06c77692ee'}
10462019-12-06 08:55:45.955 T:2684076240 DEBUG: Version Check: Version available {u'major': u'18', u'extrainfo': u'final', u'tagversion': u'', u'tag': u'stable', u'addon_support': u'yes', u'minor': u'4', u'revision': u'20190831-3ade758'}
10472019-12-06 08:55:45.955 T:2684076240 DEBUG: Version Check: There is no newer stable available
10482019-12-06 08:55:45.956 T:2684076240 INFO: CPythonInvoker(1, /home/pi/.kodi/addons/service.xbmc.versioncheck/service.py): script successfully run
10492019-12-06 08:55:45.956 T:2684076240 DEBUG: onExecutionDone(1, /home/pi/.kodi/addons/service.xbmc.versioncheck/service.py)
10502019-12-06 08:55:46.000 T:2684076240 INFO: Python interpreter stopped
10512019-12-06 08:55:46.000 T:2684076240 DEBUG: Thread LanguageInvoker 2684076240 terminating
10522019-12-06 08:55:51.134 T:2973264992 DEBUG: ------ Window Deinit (DialogNotification.xml) ------
10532019-12-06 08:56:14.208 T:2759610576 DEBUG: Thread JobWorker 2759610576 terminating (autodelete)
10542019-12-06 08:56:14.208 T:2613883088 DEBUG: Thread JobWorker 2613883088 terminating (autodelete)
10552019-12-06 08:56:14.229 T:2751217872 DEBUG: Thread JobWorker 2751217872 terminating (autodelete)
10562019-12-06 08:56:14.230 T:2768003280 DEBUG: Thread JobWorker 2768003280 terminating (autodelete)
10572019-12-06 08:56:23.871 T:2622275792 DEBUG: PushCecKeypress - received key a8 duration 0
10582019-12-06 08:56:23.907 T:2973264992 DEBUG: HandleKey: 168 (0xa8, obc87) pressed, action is Right
10592019-12-06 08:56:23.947 T:2622275792 DEBUG: PushCecKeypress - received key a8 duration 76
10602019-12-06 08:56:24.716 T:2622275792 DEBUG: PushCecKeypress - received key a8 duration 0
10612019-12-06 08:56:24.727 T:2973264992 DEBUG: HandleKey: 168 (0xa8, obc87) pressed, action is Right
10622019-12-06 08:56:24.791 T:2622275792 DEBUG: PushCecKeypress - received key a8 duration 75
10632019-12-06 08:56:25.830 T:2622275792 DEBUG: PushCecKeypress - received key b duration 0
10642019-12-06 08:56:25.835 T:2973264992 DEBUG: HandleKey: 11 (0x0b, obc244) pressed, action is Select
10652019-12-06 08:56:25.850 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers(pvr://channels/tv/All channels/pvr.hts_610901011.pvr)
10662019-12-06 08:56:25.850 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
10672019-12-06 08:56:25.850 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
10682019-12-06 08:56:25.850 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
10692019-12-06 08:56:25.850 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
10702019-12-06 08:56:25.850 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
10712019-12-06 08:56:25.850 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
10722019-12-06 08:56:25.851 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
10732019-12-06 08:56:25.851 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
10742019-12-06 08:56:25.851 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
10752019-12-06 08:56:25.851 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
10762019-12-06 08:56:25.851 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
10772019-12-06 08:56:25.851 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
10782019-12-06 08:56:25.851 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
10792019-12-06 08:56:25.851 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
10802019-12-06 08:56:25.851 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
10812019-12-06 08:56:25.851 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
10822019-12-06 08:56:25.851 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
10832019-12-06 08:56:25.854 T:2973264992 DEBUG: CMMALPool::CMMALPool Created pool 0x472f610 of size 26 x 0 for port vc.ril.video_decode:out:0(I420)
10842019-12-06 08:56:25.862 T:2973264992 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
10852019-12-06 08:56:25.862 T:2973264992 NOTICE: VideoPlayer::OpenFile: pvr://channels/tv/All channels/pvr.hts_610901011.pvr
10862019-12-06 08:56:25.862 T:2973264992 DEBUG: CMMALRenderer::CMMALRenderer
10872019-12-06 08:56:25.863 T:2751217872 DEBUG: Thread MMALProcess start, auto delete: false
10882019-12-06 08:56:25.863 T:2973264992 DEBUG: OnPlayBackStarted: CApplication::OnPlayBackStarted
10892019-12-06 08:56:25.863 T:2751217872 DEBUG: CMMALRenderer::Run - starting
10902019-12-06 08:56:25.863 T:2759610576 DEBUG: Thread VideoPlayer start, auto delete: false
10912019-12-06 08:56:25.871 T:2613883088 DEBUG: Thread JobWorker start, auto delete: true
10922019-12-06 08:56:25.872 T:2759610576 NOTICE: Creating InputStream
10932019-12-06 08:56:25.874 T:2759610576 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux close
10942019-12-06 08:56:25.874 T:2759610576 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux open
10952019-12-06 08:56:25.874 T:2973264992 DEBUG: CPlayerGUIInfo::InitCurrentItem(pvr://channels/tv/All channels/pvr.hts_610901011.pvr)
10962019-12-06 08:56:25.874 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnChanged from xbmc
10972019-12-06 08:56:25.874 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1024, from xbmc, message OnChanged
10982019-12-06 08:56:25.874 T:2684076240 DEBUG: Thread JobWorker start, auto delete: true
10992019-12-06 08:56:25.875 T:2759610576 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux subscribe to 610901011
11002019-12-06 08:56:25.880 T:2613883088 DEBUG: Loading settings for pvr://channels/tv/All channels/pvr.hts_610901011.pvr
11012019-12-06 08:56:25.884 T:2759610576 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux successfully subscribed to channel id 610901011, subscription id 1
11022019-12-06 08:56:25.884 T:2759610576 DEBUG: CInputStreamPVRChannel - OpenPVRStream - opened channel stream pvr://channels/tv/All channels/pvr.hts_610901011.pvr
11032019-12-06 08:56:25.884 T:2759610576 NOTICE: Creating Demuxer
11042019-12-06 08:56:25.885 T:2759610576 DEBUG: CVideoPlayer::SetCaching - caching state 2
11052019-12-06 08:56:25.885 T:2759610576 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
11062019-12-06 08:56:25.904 T:2622275792 DEBUG: PushCecKeypress - received key b duration 74
11072019-12-06 08:56:25.927 T:2973264992 DEBUG: CPlayerGUIInfo::InitCurrentItem(pvr://channels/tv/All channels/pvr.hts_610901011.pvr)
11082019-12-06 08:56:25.927 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnChanged from xbmc
11092019-12-06 08:56:25.927 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1024, from xbmc, message OnChanged
11102019-12-06 08:56:25.928 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
11112019-12-06 08:56:25.928 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
11122019-12-06 08:56:25.928 T:2675683536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Info.OnChanged - data: null
11132019-12-06 08:56:25.929 T:2973264992 DEBUG: CDirectoryProvider[pvr://channels/tv/*?view=lastplayed]: refreshing..
11142019-12-06 08:56:25.962 T:2675683536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Info.OnChanged - data: null
11152019-12-06 08:56:25.962 T:2675683536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"channeltype":"tv","id":6,"title":"BBC ONE HD","type":"channel"},"player":{"playerid":1,"speed":1}}
11162019-12-06 08:56:30.642 T:2630668496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux subscription start
11172019-12-06 08:56:30.646 T:2630668496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - id: 1001, type H264, codec: 27
11182019-12-06 08:56:30.646 T:2630668496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - id: 1002, type AAC, codec: 86018
11192019-12-06 08:56:30.646 T:2630668496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - id: 1004, type DVBSUB, codec: 94209
11202019-12-06 08:56:30.646 T:2630668496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux stream change
11212019-12-06 08:56:30.648 T:2759610576 DEBUG: CDVDDemuxClient::RequestStream(): added/updated stream 1001 with codec_id 27
11222019-12-06 08:56:30.648 T:2759610576 DEBUG: CDVDDemuxClient::RequestStream(): added/updated stream 1002 with codec_id 86018
11232019-12-06 08:56:30.648 T:2759610576 DEBUG: CDVDDemuxClient::RequestStream(): added/updated stream 1004 with codec_id 94209
11242019-12-06 08:56:30.649 T:2759610576 NOTICE: Opening stream: 1001 source: 256
11252019-12-06 08:56:30.649 T:2759610576 NOTICE: Whitelist search for: width: 1920, height: 1080, fps: 25.000, 3D: false
11262019-12-06 08:56:30.649 T:2759610576 DEBUG: Whitelist is empty using default one
11272019-12-06 08:56:30.649 T:2759610576 DEBUG: Trying to find exact refresh rate
11282019-12-06 08:56:30.649 T:2759610576 DEBUG: No exact whitelisted resolution matched, trying double refresh rate
11292019-12-06 08:56:30.650 T:2759610576 DEBUG: Matched fuzzy whitelisted Resolution 1920x1080 @ 50.000000 Hz (19)
11302019-12-06 08:56:30.650 T:2759610576 NOTICE: Display resolution ADJUST : 1920x1080 @ 50.000000 Hz (19) (weight: 0.000)
11312019-12-06 08:56:30.652 T:2759610576 WARNING: OpenStream - Unsupported stream 1001. Stream disabled.
11322019-12-06 08:56:30.652 T:2759610576 NOTICE: Opening stream: 1002 source: 256
11332019-12-06 08:56:30.652 T:2759610576 NOTICE: Finding audio codec for: 86018
11342019-12-06 08:56:30.671 T:2759610576 NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
11352019-12-06 08:56:30.671 T:2759610576 NOTICE: Creating audio thread
11362019-12-06 08:56:30.672 T:2500837584 DEBUG: Thread VideoPlayerAudio start, auto delete: false
11372019-12-06 08:56:30.672 T:2759610576 NOTICE: Opening stream: 1004 source: 256
11382019-12-06 08:56:30.672 T:2500837584 NOTICE: running thread: CVideoPlayerAudio::Process()
11392019-12-06 08:56:30.672 T:2500837584 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 0
11402019-12-06 08:56:30.672 T:2759610576 DEBUG: CVideoPlayer::HandleMessages - player 1 reported state: 0
11412019-12-06 08:56:30.672 T:2613883088 DEBUG: OnAVChange: CApplication::OnAVChange
11422019-12-06 08:56:30.673 T:2759610576 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:-0.050000
11432019-12-06 08:56:30.673 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnAVChange from xbmc
11442019-12-06 08:56:30.673 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVChange
11452019-12-06 08:56:30.675 T:2759610576 DEBUG: CDVDDemuxClient::ParsePacket - split extradata
11462019-12-06 08:56:30.680 T:2759610576 DEBUG: CDVDDemuxClient::ParsePacket - (1001) profile changed from -99 to 100
11472019-12-06 08:56:30.680 T:2759610576 DEBUG: CDVDDemuxClient::ParsePacket - (1001) level changed from -99 to 40
11482019-12-06 08:56:30.680 T:2759610576 DEBUG: CDVDDemuxClient::RequestStream(): added/updated stream 1001 with codec_id 27
11492019-12-06 08:56:30.680 T:2759610576 DEBUG: CDVDDemuxClient::RequestStream(): added/updated stream 1002 with codec_id 86018
11502019-12-06 08:56:30.680 T:2759610576 DEBUG: CDVDDemuxClient::RequestStream(): added/updated stream 1004 with codec_id 94209
11512019-12-06 08:56:30.681 T:2759610576 NOTICE: Opening stream: 1001 source: 256
11522019-12-06 08:56:30.681 T:2759610576 NOTICE: Whitelist search for: width: 1920, height: 1080, fps: 25.000, 3D: false
11532019-12-06 08:56:30.681 T:2759610576 DEBUG: Whitelist is empty using default one
11542019-12-06 08:56:30.681 T:2759610576 DEBUG: Trying to find exact refresh rate
11552019-12-06 08:56:30.681 T:2759610576 DEBUG: No exact whitelisted resolution matched, trying double refresh rate
11562019-12-06 08:56:30.681 T:2759610576 DEBUG: Matched fuzzy whitelisted Resolution 1920x1080 @ 50.000000 Hz (19)
11572019-12-06 08:56:30.681 T:2759610576 NOTICE: Display resolution ADJUST : 1920x1080 @ 50.000000 Hz (19) (weight: 0.000)
11582019-12-06 08:56:30.687 T:2759610576 NOTICE: Creating video codec with codec id: 27
11592019-12-06 08:56:30.692 T:2759610576 DEBUG: CMMALPool::CMMALPool Created pool 0xa52cae10 of size 26 x 128 for port vc.ril.video_decode:out:0(OPQV)
11602019-12-06 08:56:30.698 T:2759610576 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 27
11612019-12-06 08:56:30.702 T:2759610576 DEBUG: CMMALPool::CMMALPool Created pool 0xa56fc8e0 of size 26 x 0 for port vc.ril.video_decode:out:0(I420)
11622019-12-06 08:56:30.702 T:2759610576 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x472f610 for port vc.ril.video_decode:out:0(I420)
11632019-12-06 08:56:30.702 T:2759610576 NOTICE: Creating video thread
11642019-12-06 08:56:30.702 T:2768003280 DEBUG: Thread VideoPlayerVideo start, auto delete: false
11652019-12-06 08:56:30.703 T:2768003280 NOTICE: running thread: video_thread
11662019-12-06 08:56:30.703 T:2759610576 NOTICE: Opening stream: 1002 source: 256
11672019-12-06 08:56:30.703 T:2768003280 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0
11682019-12-06 08:56:30.703 T:2759610576 NOTICE: Closing stream player 3
11692019-12-06 08:56:30.703 T:2759610576 NOTICE: Opening stream: 1004 source: 256
11702019-12-06 08:56:30.703 T:2759610576 DEBUG: CVideoPlayer::HandleMessages - player 2 reported state: 0
11712019-12-06 08:56:30.703 T:2768003280 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
11722019-12-06 08:56:30.713 T:2759610576 DEBUG: CVideoPlayer::HandleMessages - player 1 reported state: 0
11732019-12-06 08:56:30.724 T:2675683536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnAVChange - data: {"item":{"channeltype":"tv","id":6,"title":"BBC ONE HD","type":"channel"},"player":{"playerid":1,"speed":1}}
11742019-12-06 08:56:30.830 T:2492444880 DEBUG: CMMALPool::Configure pool:0xa52cae10 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
11752019-12-06 08:56:30.885 T:2768003280 DEBUG: Previous line repeats 4 times.
11762019-12-06 08:56:30.888 T:2768003280 DEBUG: CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 25.00.
11772019-12-06 08:56:30.894 T:2973264992 DEBUG: DeleteRenderer - deleting renderer
11782019-12-06 08:56:30.894 T:2973264992 DEBUG: CMMALRenderer::~CMMALRenderer
11792019-12-06 08:56:30.894 T:2973264992 DEBUG: CMMALRenderer::UnInitMMAL
11802019-12-06 08:56:30.894 T:2751217872 DEBUG: CMMALRenderer::Run - stopping
11812019-12-06 08:56:30.894 T:2751217872 DEBUG: Thread MMALProcess 2751217872 terminating
11822019-12-06 08:56:30.894 T:2973264992 DEBUG: CMMALRenderer::CMMALRenderer
11832019-12-06 08:56:30.895 T:2973264992 DEBUG: CMMALRenderer::Configure - 1920x1080->1920x1080@25.00 flags:1 format:-1 orient:0
11842019-12-06 08:56:30.895 T:2973264992 DEBUG: CRenderManager::Configure - 4
11852019-12-06 08:56:30.895 T:2475659472 DEBUG: Thread MMALProcess start, auto delete: false
11862019-12-06 08:56:30.895 T:2475659472 DEBUG: CMMALRenderer::Run - starting
11872019-12-06 08:56:30.905 T:2475659472 DEBUG: CMMALPool::CMMALPool Created pool 0xaf614f90 of size 3 x 0 for port vc.ril.image_fx:out:0(I420)
11882019-12-06 08:56:30.906 T:2475659472 DEBUG: CMMALRenderer::CheckConfigurationDeint Changing Deint dimensions from 160x64 (160x64) to 1920x1080 (1920x1088) S420->OPQV mode 33->28 bpp:8
11892019-12-06 08:56:30.907 T:2973264992 DEBUG: CMMALRenderer::RenderUpdate - vsync -1 (+2687)
11902019-12-06 08:56:30.908 T:2973264992 DEBUG: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 79
11912019-12-06 08:56:30.910 T:2492444880 DEBUG: CMMALPool::Configure pool:0xa52cae10 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
11922019-12-06 08:56:30.917 T:2475659472 DEBUG: CMMALPool::Configure pool:0xaf614f90 1920x1080 (1920x1088) pix:-1 size:3133440 fmt:I420
11932019-12-06 08:56:30.924 T:2759610576 DEBUG: CVideoPlayer::HandleMessages - player started 2
11942019-12-06 08:56:30.924 T:2613883088 DEBUG: OnAVChange: CApplication::OnAVChange
11952019-12-06 08:56:30.924 T:2759610576 DEBUG: VideoPlayer::Sync - Video - pts: 1974366.000000, cache: 50000.000000, totalcache: 100000.000000
11962019-12-06 08:56:30.925 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnAVChange from xbmc
11972019-12-06 08:56:30.925 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVChange
11982019-12-06 08:56:30.926 T:2684076240 DEBUG: OnAVStarted: CApplication::OnAVStarted
11992019-12-06 08:56:30.926 T:2500837584 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(1874366.000000), level: 0, cache: 300000.000000
12002019-12-06 08:56:30.926 T:2500837584 DEBUG: CDVDAudio::Resume - resume audio stream
12012019-12-06 08:56:30.926 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnAVStart from xbmc
12022019-12-06 08:56:30.926 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVStart
12032019-12-06 08:56:30.940 T:2475659472 DEBUG: CMMALRenderer::CheckConfigurationVout Changing Vout dimensions from 0x0 (0x0) to 1920x1080 (1920x1088) I420
12042019-12-06 08:56:30.945 T:2475659472 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,1080 -> 0,0,1920,1080 (o:0 v:0 d:0 i:0)
12052019-12-06 08:56:30.945 T:2452607184 DEBUG: Thread MMALRenderer start, auto delete: false
12062019-12-06 08:56:30.945 T:2452607184 DEBUG: CMMALRenderer::Process - starting
12072019-12-06 08:56:30.945 T:2475659472 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,1080 -> 0,0,1920,1080 t:100000
12082019-12-06 08:56:30.947 T:2973264992 DEBUG: Activating window ID: 12005
12092019-12-06 08:56:30.947 T:2973264992 DEBUG: ------ Window Deinit (Home.xml) ------
12102019-12-06 08:56:30.947 T:2973264992 DEBUG: FreeVisualisation() done
12112019-12-06 08:56:30.948 T:2973264992 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
12122019-12-06 08:56:30.948 T:2973264992 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
12132019-12-06 08:56:30.951 T:2973264992 NOTICE: Whitelist search for: width: 1920, height: 1080, fps: 25.000, 3D: false
12142019-12-06 08:56:30.952 T:2973264992 DEBUG: Whitelist is empty using default one
12152019-12-06 08:56:30.952 T:2973264992 DEBUG: Trying to find exact refresh rate
12162019-12-06 08:56:30.952 T:2973264992 DEBUG: No exact whitelisted resolution matched, trying double refresh rate
12172019-12-06 08:56:30.952 T:2973264992 DEBUG: Matched fuzzy whitelisted Resolution 1920x1080 @ 50.000000 Hz (19)
12182019-12-06 08:56:30.952 T:2973264992 NOTICE: Display resolution ADJUST : 1920x1080 @ 50.000000 Hz (19) (weight: 0.000)
12192019-12-06 08:56:30.952 T:2768003280 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
12202019-12-06 08:56:30.953 T:2768003280 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(1874366.000000)
12212019-12-06 08:56:30.953 T:2973264992 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,1080 -> 0,0,1920,1080 (o:0 v:0 d:0 i:0)
12222019-12-06 08:56:30.954 T:2973264992 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,1080 -> 0,0,1920,1080 t:100000
12232019-12-06 08:56:30.954 T:2492444880 DEBUG: CMMALPool::Configure pool:0xa52cae10 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
12242019-12-06 08:56:30.954 T:2973264992 DEBUG: CMMALRenderer::RenderUpdate - vsync 2687 (+1)
12252019-12-06 08:56:30.954 T:2973264992 DEBUG: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 62
12262019-12-06 08:56:30.969 T:2475659472 DEBUG: CMMALRenderer::CheckConfigurationDeint Changing Deint dimensions from 1920x1080 (1920x1088) to 1920x1080 (1920x1088) OPQV->OPQV mode 28->27 bpp:8
12272019-12-06 08:56:30.983 T:2475659472 DEBUG: CMMALPool::Configure pool:0xaf614f90 1920x1080 (1920x1088) pix:-1 size:3133440 fmt:I420
12282019-12-06 08:56:31.000 T:2492444880 DEBUG: CMMALPool::Configure pool:0xa52cae10 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
12292019-12-06 08:56:31.024 T:2675683536 DEBUG: Previous line repeats 1 times.
12302019-12-06 08:56:31.024 T:2675683536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnAVChange - data: {"item":{"channeltype":"tv","id":6,"title":"BBC ONE HD","type":"channel"},"player":{"playerid":1,"speed":1}}
12312019-12-06 08:56:31.025 T:2675683536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnAVStart - data: {"item":{"channeltype":"tv","id":6,"title":"BBC ONE HD","type":"channel"},"player":{"playerid":1,"speed":1}}
12322019-12-06 08:56:31.026 T:2492444880 DEBUG: CMMALPool::Configure pool:0xa52cae10 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
12332019-12-06 08:56:31.026 T:2759610576 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:-0.050000
12342019-12-06 08:56:31.026 T:2492444880 DEBUG: CMMALPool::Configure pool:0xa52cae10 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
12352019-12-06 08:56:31.026 T:2613883088 DEBUG: OnAVChange: CApplication::OnAVChange
12362019-12-06 08:56:31.026 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnAVChange from xbmc
12372019-12-06 08:56:31.026 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVChange
12382019-12-06 08:56:31.125 T:2675683536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnAVChange - data: {"item":{"channeltype":"tv","id":6,"title":"BBC ONE HD","type":"channel"},"player":{"playerid":1,"speed":1}}
12392019-12-06 08:56:31.217 T:2492444880 DEBUG: CMMALPool::Configure pool:0xa52cae10 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
12402019-12-06 08:56:33.709 T:2768003280 DEBUG: Previous line repeats 49 times.
12412019-12-06 08:56:33.709 T:2768003280 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
12422019-12-06 08:56:33.728 T:2492444880 DEBUG: CMMALPool::Configure pool:0xa52cae10 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
12432019-12-06 08:56:33.933 T:2759610576 DEBUG: Previous line repeats 2 times.
12442019-12-06 08:56:33.933 T:2759610576 DEBUG: Stream stalled, start buffering. Audio: 0 - Video: 0
12452019-12-06 08:56:33.933 T:2759610576 DEBUG: CVideoPlayer::SetCaching - caching state 1
12462019-12-06 08:56:33.933 T:2759610576 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
12472019-12-06 08:56:33.936 T:2500837584 DEBUG: CDVDAudio::Pause - pausing audio stream
12482019-12-06 08:56:34.807 T:2768003280 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
12492019-12-06 08:57:01.026 T:2684076240 WARNING: Previous line repeats 50 times.
12502019-12-06 08:57:01.026 T:2684076240 DEBUG: Thread JobWorker 2684076240 terminating (autodelete)
12512019-12-06 08:57:01.026 T:2613883088 DEBUG: Thread JobWorker 2613883088 terminating (autodelete)
12522019-12-06 08:57:01.187 T:2768003280 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
12532019-12-06 08:57:12.848 T:2973264992 WARNING: Previous line repeats 22 times.
12542019-12-06 08:57:12.848 T:2973264992 NOTICE: NFS is idle. Closing the remaining connections.
12552019-12-06 08:57:13.068 T:2768003280 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
12562019-12-06 08:57:26.214 T:2931814608 WARNING: Previous line repeats 25 times.
12572019-12-06 08:57:26.214 T:2931814608 DEBUG: CAESinkPi:Drain delay:100ms now:0ms
12582019-12-06 08:57:26.467 T:2768003280 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
12592019-12-06 08:57:36.214 T:2931814608 WARNING: Previous line repeats 18 times.
12602019-12-06 08:57:36.216 T:2931814608 DEBUG: CAESinkPi:Deinitialize
12612019-12-06 08:57:36.216 T:2931814608 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000
12622019-12-06 08:57:36.225 T:2931814608 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0xae203240
12632019-12-06 08:57:36.287 T:2768003280 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
12642019-12-06 08:58:31.226 T:2622275792 WARNING: Previous line repeats 106 times.
12652019-12-06 08:58:31.226 T:2622275792 DEBUG: PushCecKeypress - received key b duration 0
12662019-12-06 08:58:31.226 T:2973264992 DEBUG: HandleKey: 11 (0x0b, obc244) pressed, action is OSD
12672019-12-06 08:58:31.226 T:2973264992 DEBUG: ------ Window Init (VideoOSD.xml) ------
12682019-12-06 08:58:31.226 T:2973264992 INFO: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY
12692019-12-06 08:58:31.242 T:2973264992 ERROR: Control 602 in window 12901 has been asked to focus, but it can't
12702019-12-06 08:58:31.243 T:2973264992 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
12712019-12-06 08:58:31.266 T:2973264992 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------
12722019-12-06 08:58:31.300 T:2622275792 DEBUG: PushCecKeypress - received key b duration 74
12732019-12-06 08:58:31.648 T:2768003280 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
12742019-12-06 08:58:32.346 T:2622275792 WARNING: Previous line repeats 1 times.
12752019-12-06 08:58:32.346 T:2622275792 DEBUG: PushCecKeypress - received key b duration 0
12762019-12-06 08:58:32.366 T:2973264992 DEBUG: HandleKey: 11 (0x0b, obc244) pressed, action is Select
12772019-12-06 08:58:32.366 T:2973264992 NOTICE: CVideoPlayer::CloseFile()
12782019-12-06 08:58:32.366 T:2931814608 INFO: CActiveAESink::OpenSink - initialize sink
12792019-12-06 08:58:32.366 T:2931814608 DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI
12802019-12-06 08:58:32.367 T:2931814608 DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:17640 bytes/s=352800.00 dest=PI:HDMI
12812019-12-06 08:58:32.367 T:2973264992 DEBUG: DeleteRenderer - deleting renderer
12822019-12-06 08:58:32.367 T:2931814608 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008
12832019-12-06 08:58:32.367 T:2973264992 DEBUG: CMMALRenderer::~CMMALRenderer
12842019-12-06 08:58:32.368 T:2931814608 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0xae203240
12852019-12-06 08:58:32.369 T:2931814608 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(17648), nBufferAlignment(16)
12862019-12-06 08:58:32.369 T:2931814608 DEBUG: CActiveAESink::OpenSink - SinkPi Initialized:
12872019-12-06 08:58:32.369 T:2931814608 DEBUG: Output Device : HDMI
12882019-12-06 08:58:32.369 T:2931814608 DEBUG: Sample Rate : 44100
12892019-12-06 08:58:32.369 T:2931814608 DEBUG: Sample Format : AE_FMT_FLOAT
12902019-12-06 08:58:32.369 T:2931814608 DEBUG: Channel Count : 2
12912019-12-06 08:58:32.370 T:2931814608 DEBUG: Channel Layout: FL, FR
12922019-12-06 08:58:32.370 T:2931814608 DEBUG: Frames : 2205
12932019-12-06 08:58:32.370 T:2931814608 DEBUG: Frame Size : 8
12942019-12-06 08:58:32.372 T:2973264992 DEBUG: CMMALRenderer::UnInitMMAL
12952019-12-06 08:58:32.386 T:2452607184 DEBUG: CMMALRenderer::Process - stopping
12962019-12-06 08:58:32.386 T:2452607184 DEBUG: Thread MMALRenderer 2452607184 terminating
12972019-12-06 08:58:32.408 T:2475659472 DEBUG: CMMALPool::~CMMALPool Destroying pool 0xaf614f90 for port vc.ril.image_fx:out:0(I420)
12982019-12-06 08:58:32.414 T:2475659472 DEBUG: CMMALRenderer::Run - stopping
12992019-12-06 08:58:32.414 T:2475659472 DEBUG: Thread MMALProcess 2475659472 terminating
13002019-12-06 08:58:32.414 T:2973264992 NOTICE: VideoPlayer: waiting for threads to exit
13012019-12-06 08:58:32.420 T:2622275792 DEBUG: PushCecKeypress - received key b duration 74
13022019-12-06 08:58:32.464 T:2759610576 NOTICE: CVideoPlayer::OnExit()
13032019-12-06 08:58:32.464 T:2759610576 DEBUG: CVideoPlayer::SetCaching - caching state 0
13042019-12-06 08:58:32.464 T:2500837584 DEBUG: CDVDAudio::Resume - resume audio stream
13052019-12-06 08:58:32.464 T:2759610576 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
13062019-12-06 08:58:32.464 T:2500837584 NOTICE: CVideoPlayerAudio::Process - stream stalled
13072019-12-06 08:58:32.464 T:2759610576 NOTICE: Closing stream player 1
13082019-12-06 08:58:32.464 T:2759610576 NOTICE: Waiting for audio thread to exit
13092019-12-06 08:58:32.474 T:2500837584 NOTICE: thread end: CVideoPlayerAudio::OnExit()
13102019-12-06 08:58:32.474 T:2500837584 DEBUG: Thread VideoPlayerAudio 2500837584 terminating
13112019-12-06 08:58:32.474 T:2759610576 NOTICE: Closing audio device
13122019-12-06 08:58:32.474 T:2759610576 NOTICE: Deleting audio codec
13132019-12-06 08:58:32.475 T:2759610576 NOTICE: Closing stream player 2
13142019-12-06 08:58:32.475 T:2759610576 NOTICE: waiting for video thread to exit
13152019-12-06 08:58:32.516 T:2768003280 NOTICE: thread end: video_thread
13162019-12-06 08:58:32.516 T:2768003280 DEBUG: Thread VideoPlayerVideo 2768003280 terminating
13172019-12-06 08:58:32.516 T:2759610576 NOTICE: deleting video codec
13182019-12-06 08:58:32.519 T:2759610576 DEBUG: CMMALPool::~CMMALPool Destroying pool 0xa52cae10 for port vc.ril.video_decode:out:0(OPQV)
13192019-12-06 08:58:32.522 T:2759610576 NOTICE: Closing stream player 3
13202019-12-06 08:58:32.523 T:2492444880 DEBUG: Thread JobWorker start, auto delete: true
13212019-12-06 08:58:32.523 T:2759610576 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux unsubscribe from 610901011
13222019-12-06 08:58:32.525 T:2759610576 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux successfully unsubscribed from channel id 610901011, subscription id 1
13232019-12-06 08:58:32.525 T:2759610576 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux close
13242019-12-06 08:58:32.525 T:2759610576 DEBUG: CInputStreamPVRChannel - ClosePVRStream - closed channel stream pvr://channels/tv/All channels/pvr.hts_610901011.pvr
13252019-12-06 08:58:32.525 T:2759610576 DEBUG: Thread VideoPlayer 2759610576 terminating
13262019-12-06 08:58:32.525 T:2973264992 NOTICE: VideoPlayer: finished waiting
13272019-12-06 08:58:32.525 T:2973264992 NOTICE: CVideoPlayer::CloseFile()
13282019-12-06 08:58:32.526 T:2973264992 NOTICE: VideoPlayer: waiting for threads to exit
13292019-12-06 08:58:32.526 T:2973264992 NOTICE: VideoPlayer: finished waiting
13302019-12-06 08:58:32.527 T:2973264992 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
13312019-12-06 08:58:32.528 T:2492444880 INFO: Deleting settings information for files pvr://channels/tv/All channels/pvr.hts_610901011.pvr
13322019-12-06 08:58:32.528 T:2492444880 DEBUG: Mysql execute: DELETE FROM settings WHERE idFile=3957
13332019-12-06 08:58:32.529 T:2759610576 DEBUG: Thread JobWorker start, auto delete: true
13342019-12-06 08:58:32.529 T:2492444880 DEBUG: OnPlayBackStopped: CApplication::OnPlayBackStopped
13352019-12-06 08:58:32.534 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
13362019-12-06 08:58:32.534 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
13372019-12-06 08:58:32.537 T:2973264992 DEBUG: CMMALPool::~CMMALPool Destroying pool 0xa56fc8e0 for port vc.ril.video_decode:out:0(I420)
13382019-12-06 08:58:32.538 T:2973264992 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
13392019-12-06 08:58:32.538 T:2973264992 DEBUG: ------ Window Deinit (VideoOSD.xml) ------
13402019-12-06 08:58:32.538 T:2973264992 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
13412019-12-06 08:58:32.542 T:2973264992 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
13422019-12-06 08:58:32.542 T:2973264992 DEBUG: ------ Window Init (Home.xml) ------
13432019-12-06 08:58:32.542 T:2973264992 DEBUG: CDirectoryProvider[pvr://channels/tv/*?view=lastplayed]: refreshing..
13442019-12-06 08:58:32.542 T:2973264992 DEBUG: CDirectoryProvider[plugin://script.skin.helper.widgets/?action=next&mediatype=episodes&reload=]: refreshing..
13452019-12-06 08:58:32.542 T:2973264992 DEBUG: CDirectoryProvider[videodb://inprogresstvshows]: refreshing..
13462019-12-06 08:58:32.542 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_episodes.xsp]: refreshing..
13472019-12-06 08:58:32.542 T:2484052176 DEBUG: Thread JobWorker start, auto delete: true
13482019-12-06 08:58:32.543 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_tvshows.xsp]: refreshing..
13492019-12-06 08:58:32.543 T:2973264992 DEBUG: CDirectoryProvider[videodb://tvshows/genres/]: refreshing..
13502019-12-06 08:58:32.543 T:2973264992 DEBUG: CDirectoryProvider[videodb://tvshows/studios/]: refreshing..
13512019-12-06 08:58:32.543 T:2973264992 DEBUG: CDirectoryProvider[library://video/tvshows/]: refreshing..
13522019-12-06 08:58:32.543 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/inprogress_movies.xsp]: refreshing..
13532019-12-06 08:58:32.543 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_movies.xsp]: refreshing..
13542019-12-06 08:58:32.543 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_movies.xsp]: refreshing..
13552019-12-06 08:58:32.543 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/random_movies.xsp]: refreshing..
13562019-12-06 08:58:32.543 T:2973264992 DEBUG: CDirectoryProvider[videodb://movies/genres/]: refreshing..
13572019-12-06 08:58:32.543 T:2973264992 DEBUG: CDirectoryProvider[library://video/movies/]: refreshing..
13582019-12-06 08:58:32.543 T:2759610576 DEBUG: StartScript - calling plugin Skin Helper Service Widgets('plugin://script.skin.helper.widgets/','2','?action=next&mediatype=episodes&reload=','resume:false')
13592019-12-06 08:58:32.543 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing..
13602019-12-06 08:58:32.543 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing..
13612019-12-06 08:58:32.543 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/game/]: refreshing..
13622019-12-06 08:58:32.543 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing..
13632019-12-06 08:58:32.543 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing..
13642019-12-06 08:58:32.543 T:2973264992 DEBUG: CDirectoryProvider[addons://]: refreshing..
13652019-12-06 08:58:32.543 T:2973264992 DEBUG: CDirectoryProvider[library://video/]: refreshing..
13662019-12-06 08:58:32.543 T:2768003280 DEBUG: Thread LanguageInvoker start, auto delete: false
13672019-12-06 08:58:32.544 T:2973264992 DEBUG: CDirectoryProvider[sources://video/]: refreshing..
13682019-12-06 08:58:32.544 T:2973264992 DEBUG: CDirectoryProvider[special://videoplaylists/]: refreshing..
13692019-12-06 08:58:32.544 T:2768003280 INFO: initializing python engine.
13702019-12-06 08:58:32.544 T:2768003280 DEBUG: CPythonInvoker(3, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): start processing
13712019-12-06 08:58:32.544 T:2973264992 DEBUG: CDirectoryProvider[pvr://channels/radio/*?view=lastplayed]: refreshing..
13722019-12-06 08:58:32.544 T:2973264992 DEBUG: CDirectoryProvider[pvr://recordings/radio/active?view=flat]: refreshing..
13732019-12-06 08:58:32.544 T:2973264992 DEBUG: CDirectoryProvider[favourites://]: refreshing..
13742019-12-06 08:58:32.544 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/game/]: refreshing..
13752019-12-06 08:58:32.688 T:2675683536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnStop - data: {"end":false,"item":{"channeltype":"tv","id":6,"title":"BBC ONE HD","type":"channel"}}
13762019-12-06 08:58:32.693 T:2768003280 DEBUG: -->Python Interpreter Initialized<--
13772019-12-06 08:58:32.693 T:2768003280 DEBUG: CPythonInvoker(3, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): the source file to load is "/home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py"
13782019-12-06 08:58:32.694 T:2768003280 DEBUG: CPythonInvoker(3, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): setting the Python path to /home/pi/.kodi/addons/script.skin.helper.widgets:/home/pi/.kodi/addons/script.module.arrow/lib:/home/pi/.kodi/addons/script.module.beautifulsoup/lib:/home/pi/.kodi/addons/script.module.certifi/lib:/home/pi/.kodi/addons/script.module.chardet/lib:/home/pi/.kodi/addons/script.module.dateutil/lib:/home/pi/.kodi/addons/script.module.idna/lib:/home/pi/.kodi/addons/script.module.metadatautils/lib:/home/pi/.kodi/addons/script.module.musicbrainz/lib:/home/pi/.kodi/addons/script.module.requests/lib:/home/pi/.kodi/addons/script.module.simplecache/lib:/home/pi/.kodi/addons/script.module.simplejson/lib:/home/pi/.kodi/addons/script.module.six/lib:/home/pi/.kodi/addons/script.module.thetvdb/lib:/home/pi/.kodi/addons/script.module.urllib3/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-arm-linux-gnueabihf:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages
13792019-12-06 08:58:32.694 T:2768003280 DEBUG: CPythonInvoker(3, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): entering source directory /home/pi/.kodi/addons/script.skin.helper.widgets
13802019-12-06 08:58:32.695 T:2768003280 DEBUG: CPythonInvoker(3, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): instantiating addon using automatically obtained id of "script.skin.helper.widgets" dependent on version 2.24.0 of the xbmc.python api
13812019-12-06 08:58:32.909 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording I'm A Celebrity...Get Me...
13822019-12-06 08:58:32.910 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording EastEnders
13832019-12-06 08:58:32.911 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording New: I'm A Celebrity...Get Me...
13842019-12-06 08:58:32.911 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording The Sheriffs Are Coming
13852019-12-06 08:58:32.912 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording New: I'm A Celebrity...Get Me...
13862019-12-06 08:58:32.913 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording EastEnders
13872019-12-06 08:58:32.935 T:2973264992 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
13882019-12-06 08:58:33.072 T:2973264992 DEBUG: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 79
13892019-12-06 08:58:33.106 T:2973264992 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------
13902019-12-06 08:58:33.107 T:2973264992 DEBUG: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 62
13912019-12-06 08:58:33.201 T:2492444880 DEBUG: CAddonMgr::GetAvailableUpdates took 29 ms
13922019-12-06 08:58:33.219 T:2492444880 DEBUG: NFS: Context for 192.168.1.16/media/2TB not open - get a new context.
13932019-12-06 08:58:33.223 T:2484052176 DEBUG: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
13942019-12-06 08:58:33.223 T:2484052176 DEBUG: Getting Directory (special://profile/playlists/video)
13952019-12-06 08:58:33.224 T:2484052176 DEBUG: Getting Directory (special://profile/playlists/mixed)
13962019-12-06 08:58:33.224 T:2484052176 DEBUG: CMultiPathDirectory::MergeItems, items = 0
13972019-12-06 08:58:33.226 T:2492444880 DEBUG: NFS: Connected to server 192.168.1.16 and export /media/2TB
13982019-12-06 08:58:33.226 T:2492444880 DEBUG: NFS: chunks: r/w 65536/65536
13992019-12-06 08:58:33.229 T:2492444880 DEBUG: NFS: Context for 192.168.1.16/media/3TB not open - get a new context.
14002019-12-06 08:58:33.233 T:2484052176 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
14012019-12-06 08:58:33.235 T:2492444880 DEBUG: NFS: Connected to server 192.168.1.16 and export /media/3TB
14022019-12-06 08:58:33.235 T:2492444880 DEBUG: NFS: chunks: r/w 65536/65536
14032019-12-06 08:58:33.238 T:2492444880 DEBUG: NFS: Context for 192.168.1.16/media/4TB not open - get a new context.
14042019-12-06 08:58:33.241 T:2484052176 DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.*, songartistview.* FROM (SELECT idAlbum FROM album ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum = recentalbums.idAlbum JOIN songartistview ON songview.idSong = songartistview.idSong ORDER BY songview.idAlbum DESC, songview.idSong, songartistview.idRole, songartistview.iOrder
14052019-12-06 08:58:33.243 T:2484052176 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
14062019-12-06 08:58:33.243 T:2492444880 DEBUG: NFS: Connected to server 192.168.1.16 and export /media/4TB
14072019-12-06 08:58:33.243 T:2492444880 DEBUG: NFS: chunks: r/w 65536/65536
14082019-12-06 08:58:33.245 T:2484052176 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
14092019-12-06 08:58:33.259 T:2492444880 DEBUG: NFS: Refreshing context for 192.168.1.16/media/2TB, old: 180334, new: 180373
14102019-12-06 08:58:33.259 T:2492444880 DEBUG: NFS: Using cached context.
14112019-12-06 08:58:33.263 T:2492444880 DEBUG: NFS: Refreshing context for 192.168.1.16/media/3TB, old: 180343, new: 180377
14122019-12-06 08:58:33.263 T:2492444880 DEBUG: NFS: Using cached context.
14132019-12-06 08:58:33.267 T:2492444880 DEBUG: NFS: Refreshing context for 192.168.1.16/media/4TB, old: 180353, new: 180381
14142019-12-06 08:58:33.267 T:2492444880 DEBUG: NFS: Using cached context.
14152019-12-06 08:58:33.289 T:2484052176 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
14162019-12-06 08:58:33.292 T:2484052176 DEBUG: GetArtistsByWhere query: SELECT COUNT(DISTINCT artistview.idArtist) FROM artistview WHERE (EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artistview.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '')
14172019-12-06 08:58:33.848 T:2768003280 DEBUG: Skin Helper Simplecache --> Initialized
14182019-12-06 08:58:33.848 T:2768003280 DEBUG: CAddonSettings[script.module.metadatautils]: loading setting definitions
14192019-12-06 08:58:33.848 T:2768003280 DEBUG: CAddonSettings[script.module.metadatautils]: trying to load setting definitions from old format...
14202019-12-06 08:58:33.850 T:2768003280 DEBUG: Skin Helper Simplecache --> Initialized
14212019-12-06 08:58:33.850 T:2768003280 DEBUG: script.module.thetvdb --> Initialized
14222019-12-06 08:58:33.850 T:2768003280 DEBUG: Skin Helper Simplecache --> Initialized
14232019-12-06 08:58:33.908 T:2768003280 DEBUG: Previous line repeats 1 times.
14242019-12-06 08:58:33.908 T:2768003280 DEBUG: CAddonSettings[script.module.metadatautils]: loading setting definitions
14252019-12-06 08:58:33.908 T:2768003280 DEBUG: CAddonSettings[script.module.metadatautils]: trying to load setting definitions from old format...
14262019-12-06 08:58:33.909 T:2768003280 DEBUG: Skin Helper Simplecache --> Initialized
14272019-12-06 08:58:33.909 T:2768003280 DEBUG: Metadata and Artwork module --> Initialized
14282019-12-06 08:58:33.909 T:2768003280 DEBUG: Skin Helper Simplecache --> Initialized
14292019-12-06 08:58:33.910 T:2768003280 DEBUG: CAddonSettings[script.skin.helper.widgets]: loading setting definitions
14302019-12-06 08:58:33.910 T:2768003280 DEBUG: CAddonSettings[script.skin.helper.widgets]: trying to load setting definitions from old format...
14312019-12-06 08:58:33.917 T:2768003280 DEBUG: Skin Helper Widgets --> MEDIATYPE: episodes - ACTION: next -- got items from cache - CHECKSUM: action.nextepisodes_enable_specials.Falsegroup_episodes.Falsehide_watched.Falselimit.25mediatype.episodesnext_inprogress_only.True
14322019-12-06 08:58:33.966 T:2500837584 ERROR: NEWADDON Unknown Video Info Key "progress"
14332019-12-06 08:58:34.029 T:2768003280 ERROR: Previous line repeats 3 times.
14342019-12-06 08:58:34.029 T:2768003280 DEBUG: Skin Helper Simplecache --> Closed
14352019-12-06 08:58:34.030 T:2768003280 DEBUG: Previous line repeats 4 times.
14362019-12-06 08:58:34.030 T:2768003280 DEBUG: Metadata and Artwork module --> Exited
14372019-12-06 08:58:34.030 T:2768003280 DEBUG: Skin Helper Simplecache --> Closed
14382019-12-06 08:58:34.030 T:2768003280 DEBUG: Skin Helper Widgets --> MainModule exited
14392019-12-06 08:58:34.030 T:2768003280 INFO: CPythonInvoker(3, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): script successfully run
14402019-12-06 08:58:34.030 T:2768003280 DEBUG: onExecutionDone(3, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py)
14412019-12-06 08:58:34.046 T:2768003280 INFO: Python interpreter stopped
14422019-12-06 08:58:34.046 T:2768003280 DEBUG: Thread LanguageInvoker 2768003280 terminating
14432019-12-06 08:59:03.272 T:2492444880 DEBUG: Thread JobWorker 2492444880 terminating (autodelete)
14442019-12-06 08:59:03.300 T:2484052176 DEBUG: Thread JobWorker 2484052176 terminating (autodelete)
14452019-12-06 08:59:04.043 T:2759610576 DEBUG: Thread JobWorker 2759610576 terminating (autodelete)
14462019-12-06 08:59:32.678 T:2931814608 DEBUG: CAESinkPi:Drain delay:100ms now:0ms
14472019-12-06 08:59:42.678 T:2931814608 DEBUG: CAESinkPi:Deinitialize
14482019-12-06 08:59:42.678 T:2931814608 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000
14492019-12-06 08:59:42.690 T:2931814608 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0xae203240
14502019-12-06 09:00:04.127 T:2622275792 DEBUG: PushCecKeypress - received key a8 duration 0
14512019-12-06 09:00:04.140 T:2973264992 DEBUG: HandleKey: 168 (0xa8, obc87) pressed, action is Right
14522019-12-06 09:00:04.141 T:2931814608 INFO: CActiveAESink::OpenSink - initialize sink
14532019-12-06 09:00:04.141 T:2931814608 DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI
14542019-12-06 09:00:04.141 T:2931814608 DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:17640 bytes/s=352800.00 dest=PI:HDMI
14552019-12-06 09:00:04.142 T:2931814608 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008
14562019-12-06 09:00:04.144 T:2931814608 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0xae203240
14572019-12-06 09:00:04.146 T:2931814608 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(17648), nBufferAlignment(16)
14582019-12-06 09:00:04.147 T:2931814608 DEBUG: CActiveAESink::OpenSink - SinkPi Initialized:
14592019-12-06 09:00:04.147 T:2931814608 DEBUG: Output Device : HDMI
14602019-12-06 09:00:04.147 T:2931814608 DEBUG: Sample Rate : 44100
14612019-12-06 09:00:04.147 T:2931814608 DEBUG: Sample Format : AE_FMT_FLOAT
14622019-12-06 09:00:04.147 T:2931814608 DEBUG: Channel Count : 2
14632019-12-06 09:00:04.147 T:2931814608 DEBUG: Channel Layout: FL, FR
14642019-12-06 09:00:04.147 T:2931814608 DEBUG: Frames : 2205
14652019-12-06 09:00:04.147 T:2931814608 DEBUG: Frame Size : 8
14662019-12-06 09:00:04.200 T:2622275792 DEBUG: PushCecKeypress - received key a8 duration 73
14672019-12-06 09:00:04.785 T:2622275792 DEBUG: PushCecKeypress - received key b duration 0
14682019-12-06 09:00:04.805 T:2973264992 DEBUG: HandleKey: 11 (0x0b, obc244) pressed, action is Select
14692019-12-06 09:00:04.845 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers(pvr://channels/tv/All channels/pvr.hts_1906726315.pvr)
14702019-12-06 09:00:04.845 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
14712019-12-06 09:00:04.846 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
14722019-12-06 09:00:04.846 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
14732019-12-06 09:00:04.846 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
14742019-12-06 09:00:04.846 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
14752019-12-06 09:00:04.846 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
14762019-12-06 09:00:04.846 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
14772019-12-06 09:00:04.846 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
14782019-12-06 09:00:04.846 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
14792019-12-06 09:00:04.846 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
14802019-12-06 09:00:04.846 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
14812019-12-06 09:00:04.846 T:2973264992 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
14822019-12-06 09:00:04.846 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
14832019-12-06 09:00:04.846 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
14842019-12-06 09:00:04.847 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
14852019-12-06 09:00:04.847 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
14862019-12-06 09:00:04.847 T:2973264992 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
14872019-12-06 09:00:04.849 T:2973264992 DEBUG: CMMALPool::CMMALPool Created pool 0x4c2ed10 of size 26 x 0 for port vc.ril.video_decode:out:0(I420)
14882019-12-06 09:00:04.853 T:2973264992 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
14892019-12-06 09:00:04.853 T:2973264992 NOTICE: VideoPlayer::OpenFile: pvr://channels/tv/All channels/pvr.hts_1906726315.pvr
14902019-12-06 09:00:04.853 T:2973264992 DEBUG: CMMALRenderer::CMMALRenderer
14912019-12-06 09:00:04.853 T:2484052176 DEBUG: Thread MMALProcess start, auto delete: false
14922019-12-06 09:00:04.854 T:2484052176 DEBUG: CMMALRenderer::Run - starting
14932019-12-06 09:00:04.854 T:2973264992 DEBUG: OnPlayBackStarted: CApplication::OnPlayBackStarted
14942019-12-06 09:00:04.855 T:2492444880 DEBUG: Thread VideoPlayer start, auto delete: false
14952019-12-06 09:00:04.855 T:2492444880 NOTICE: Creating InputStream
14962019-12-06 09:00:04.855 T:2768003280 DEBUG: Thread JobWorker start, auto delete: true
14972019-12-06 09:00:04.856 T:2492444880 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux close
14982019-12-06 09:00:04.856 T:2492444880 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux open
14992019-12-06 09:00:04.856 T:2492444880 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux subscribe to 1906726315
15002019-12-06 09:00:04.857 T:2492444880 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux successfully subscribed to channel id 1906726315, subscription id 2
15012019-12-06 09:00:04.857 T:2492444880 DEBUG: CInputStreamPVRChannel - OpenPVRStream - opened channel stream pvr://channels/tv/All channels/pvr.hts_1906726315.pvr
15022019-12-06 09:00:04.857 T:2492444880 NOTICE: Creating Demuxer
15032019-12-06 09:00:04.858 T:2492444880 DEBUG: CVideoPlayer::SetCaching - caching state 2
15042019-12-06 09:00:04.858 T:2492444880 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
15052019-12-06 09:00:04.861 T:2622275792 DEBUG: PushCecKeypress - received key b duration 76
15062019-12-06 09:00:04.865 T:2768003280 DEBUG: Loading settings for pvr://channels/tv/All channels/pvr.hts_1906726315.pvr
15072019-12-06 09:00:04.867 T:2973264992 DEBUG: CPlayerGUIInfo::InitCurrentItem(pvr://channels/tv/All channels/pvr.hts_1906726315.pvr)
15082019-12-06 09:00:04.867 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnChanged from xbmc
15092019-12-06 09:00:04.867 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1024, from xbmc, message OnChanged
15102019-12-06 09:00:04.867 T:2526015696 DEBUG: Thread JobWorker start, auto delete: true
15112019-12-06 09:00:04.905 T:2973264992 DEBUG: CPlayerGUIInfo::InitCurrentItem(pvr://channels/tv/All channels/pvr.hts_1906726315.pvr)
15122019-12-06 09:00:04.906 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnChanged from xbmc
15132019-12-06 09:00:04.906 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1024, from xbmc, message OnChanged
15142019-12-06 09:00:04.906 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
15152019-12-06 09:00:04.906 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
15162019-12-06 09:00:04.907 T:2973264992 DEBUG: CDirectoryProvider[pvr://channels/tv/*?view=lastplayed]: refreshing..
15172019-12-06 09:00:04.930 T:2675683536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Info.OnChanged - data: null
15182019-12-06 09:00:04.931 T:2675683536 DEBUG: Previous line repeats 1 times.
15192019-12-06 09:00:04.931 T:2675683536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"channeltype":"tv","id":17,"title":"ITV HD","type":"channel"},"player":{"playerid":1,"speed":1}}
15202019-12-06 09:00:06.065 T:2973264992 NOTICE: NFS is idle. Closing the remaining connections.
15212019-12-06 09:00:07.518 T:2630668496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux subscription start
15222019-12-06 09:00:07.518 T:2630668496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - id: 1001, type H264, codec: 27
15232019-12-06 09:00:07.519 T:2630668496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - id: 1002, type AAC, codec: 86018
15242019-12-06 09:00:07.519 T:2630668496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - id: 1004, type DVBSUB, codec: 94209
15252019-12-06 09:00:07.519 T:2630668496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux stream change
15262019-12-06 09:00:07.519 T:2492444880 DEBUG: CDVDDemuxClient::RequestStream(): added/updated stream 1001 with codec_id 27
15272019-12-06 09:00:07.519 T:2492444880 DEBUG: CDVDDemuxClient::RequestStream(): added/updated stream 1002 with codec_id 86018
15282019-12-06 09:00:07.519 T:2492444880 DEBUG: CDVDDemuxClient::RequestStream(): added/updated stream 1004 with codec_id 94209
15292019-12-06 09:00:07.519 T:2492444880 NOTICE: Opening stream: 1001 source: 256
15302019-12-06 09:00:07.519 T:2492444880 NOTICE: Whitelist search for: width: 1920, height: 1080, fps: 25.000, 3D: false
15312019-12-06 09:00:07.519 T:2492444880 DEBUG: Whitelist is empty using default one
15322019-12-06 09:00:07.519 T:2492444880 DEBUG: Trying to find exact refresh rate
15332019-12-06 09:00:07.520 T:2492444880 DEBUG: No exact whitelisted resolution matched, trying double refresh rate
15342019-12-06 09:00:07.520 T:2492444880 DEBUG: Matched fuzzy whitelisted Resolution 1920x1080 @ 50.000000 Hz (19)
15352019-12-06 09:00:07.520 T:2492444880 NOTICE: Display resolution ADJUST : 1920x1080 @ 50.000000 Hz (19) (weight: 0.000)
15362019-12-06 09:00:07.545 T:2492444880 WARNING: OpenStream - Unsupported stream 1001. Stream disabled.
15372019-12-06 09:00:07.545 T:2492444880 NOTICE: Opening stream: 1002 source: 256
15382019-12-06 09:00:07.545 T:2492444880 NOTICE: Finding audio codec for: 86018
15392019-12-06 09:00:07.546 T:2492444880 NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
15402019-12-06 09:00:07.546 T:2492444880 NOTICE: Creating audio thread
15412019-12-06 09:00:07.546 T:2500837584 DEBUG: Thread VideoPlayerAudio start, auto delete: false
15422019-12-06 09:00:07.547 T:2500837584 NOTICE: running thread: CVideoPlayerAudio::Process()
15432019-12-06 09:00:07.547 T:2492444880 NOTICE: Opening stream: 1004 source: 256
15442019-12-06 09:00:07.547 T:2500837584 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 0
15452019-12-06 09:00:07.547 T:2492444880 DEBUG: CVideoPlayer::HandleMessages - player 1 reported state: 0
15462019-12-06 09:00:07.547 T:2768003280 DEBUG: OnAVChange: CApplication::OnAVChange
15472019-12-06 09:00:07.547 T:2492444880 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:-0.050000
15482019-12-06 09:00:07.547 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnAVChange from xbmc
15492019-12-06 09:00:07.548 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVChange
15502019-12-06 09:00:07.549 T:2492444880 DEBUG: CDVDDemuxClient::ParsePacket - split extradata
15512019-12-06 09:00:07.549 T:2492444880 DEBUG: CDVDDemuxClient::ParsePacket - (1001) profile changed from -99 to 100
15522019-12-06 09:00:07.549 T:2492444880 DEBUG: CDVDDemuxClient::ParsePacket - (1001) level changed from -99 to 40
15532019-12-06 09:00:07.549 T:2492444880 DEBUG: CDVDDemuxClient::RequestStream(): added/updated stream 1001 with codec_id 27
15542019-12-06 09:00:07.550 T:2492444880 DEBUG: CDVDDemuxClient::RequestStream(): added/updated stream 1002 with codec_id 86018
15552019-12-06 09:00:07.550 T:2492444880 DEBUG: CDVDDemuxClient::RequestStream(): added/updated stream 1004 with codec_id 94209
15562019-12-06 09:00:07.550 T:2492444880 NOTICE: Opening stream: 1001 source: 256
15572019-12-06 09:00:07.550 T:2492444880 NOTICE: Whitelist search for: width: 1920, height: 1080, fps: 25.000, 3D: false
15582019-12-06 09:00:07.550 T:2492444880 DEBUG: Whitelist is empty using default one
15592019-12-06 09:00:07.550 T:2492444880 DEBUG: Trying to find exact refresh rate
15602019-12-06 09:00:07.551 T:2492444880 DEBUG: No exact whitelisted resolution matched, trying double refresh rate
15612019-12-06 09:00:07.551 T:2492444880 DEBUG: Matched fuzzy whitelisted Resolution 1920x1080 @ 50.000000 Hz (19)
15622019-12-06 09:00:07.551 T:2492444880 NOTICE: Display resolution ADJUST : 1920x1080 @ 50.000000 Hz (19) (weight: 0.000)
15632019-12-06 09:00:07.585 T:2492444880 NOTICE: Creating video codec with codec id: 27
15642019-12-06 09:00:07.589 T:2492444880 DEBUG: CMMALPool::CMMALPool Created pool 0x9a35bea0 of size 26 x 128 for port vc.ril.video_decode:out:0(OPQV)
15652019-12-06 09:00:07.595 T:2492444880 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 27
15662019-12-06 09:00:07.599 T:2492444880 DEBUG: CMMALPool::CMMALPool Created pool 0x9a0f96b0 of size 26 x 0 for port vc.ril.video_decode:out:0(I420)
15672019-12-06 09:00:07.599 T:2492444880 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x4c2ed10 for port vc.ril.video_decode:out:0(I420)
15682019-12-06 09:00:07.599 T:2492444880 NOTICE: Creating video thread
15692019-12-06 09:00:07.599 T:2759610576 DEBUG: Thread VideoPlayerVideo start, auto delete: false
15702019-12-06 09:00:07.599 T:2492444880 NOTICE: Opening stream: 1002 source: 256
15712019-12-06 09:00:07.600 T:2759610576 NOTICE: running thread: video_thread
15722019-12-06 09:00:07.600 T:2492444880 NOTICE: Closing stream player 3
15732019-12-06 09:00:07.600 T:2759610576 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0
15742019-12-06 09:00:07.600 T:2492444880 NOTICE: Opening stream: 1004 source: 256
15752019-12-06 09:00:07.600 T:2492444880 DEBUG: CVideoPlayer::HandleMessages - player 2 reported state: 0
15762019-12-06 09:00:07.600 T:2759610576 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
15772019-12-06 09:00:07.607 T:2492444880 DEBUG: CVideoPlayer::HandleMessages - player 1 reported state: 0
15782019-12-06 09:00:07.608 T:2500837584 DEBUG: CVideoPlayerAudio: stream props changed, checking for passthrough
15792019-12-06 09:00:07.611 T:2500837584 NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
15802019-12-06 09:00:07.611 T:2500837584 NOTICE: Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through)
15812019-12-06 09:00:07.611 T:2500837584 DEBUG: CVideoPlayerAudio:: synctype set to 1: resample
15822019-12-06 09:00:07.634 T:2675683536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnAVChange - data: {"item":{"channeltype":"tv","id":17,"title":"ITV HD","type":"channel"},"player":{"playerid":1,"speed":1}}
15832019-12-06 09:00:07.650 T:2931814608 INFO: CActiveAESink::OpenSink - initialize sink
15842019-12-06 09:00:07.668 T:2475659472 DEBUG: CMMALPool::Configure pool:0x9a35bea0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
15852019-12-06 09:00:07.727 T:2759610576 DEBUG: Previous line repeats 4 times.
15862019-12-06 09:00:07.728 T:2759610576 DEBUG: CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 25.00.
15872019-12-06 09:00:07.749 T:2931814608 DEBUG: CAESinkPi:Drain delay:99ms now:0ms
15882019-12-06 09:00:07.749 T:2931814608 DEBUG: CAESinkPi:Deinitialize
15892019-12-06 09:00:07.750 T:2931814608 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000
15902019-12-06 09:00:07.750 T:2973264992 DEBUG: DeleteRenderer - deleting renderer
15912019-12-06 09:00:07.750 T:2973264992 DEBUG: CMMALRenderer::~CMMALRenderer
15922019-12-06 09:00:07.750 T:2973264992 DEBUG: CMMALRenderer::UnInitMMAL
15932019-12-06 09:00:07.750 T:2484052176 DEBUG: CMMALRenderer::Run - stopping
15942019-12-06 09:00:07.751 T:2484052176 DEBUG: Thread MMALProcess 2484052176 terminating
15952019-12-06 09:00:07.751 T:2973264992 DEBUG: CMMALRenderer::CMMALRenderer
15962019-12-06 09:00:07.751 T:2973264992 DEBUG: CMMALRenderer::Configure - 1920x1080->1920x1080@25.00 flags:21 format:-1 orient:0
15972019-12-06 09:00:07.751 T:2458874064 DEBUG: Thread MMALProcess start, auto delete: false
15982019-12-06 09:00:07.751 T:2973264992 DEBUG: CRenderManager::Configure - 4
15992019-12-06 09:00:07.751 T:2458874064 DEBUG: CMMALRenderer::Run - starting
16002019-12-06 09:00:07.751 T:2475659472 DEBUG: CMMALPool::Configure pool:0x9a35bea0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
16012019-12-06 09:00:07.762 T:2931814608 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0xae203240
16022019-12-06 09:00:07.763 T:2931814608 DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI
16032019-12-06 09:00:07.763 T:2931814608 DEBUG: CAESinkPi:Initialize Format:24 Channels:2 Samplerate:48000 framesize:8 bufsize:19200 bytes/s=384000.00 dest=PI:HDMI
16042019-12-06 09:00:07.763 T:2458874064 DEBUG: CMMALPool::CMMALPool Created pool 0x9fb6b7b0 of size 3 x 0 for port vc.ril.image_fx:out:0(I420)
16052019-12-06 09:00:07.763 T:2931814608 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008
16062019-12-06 09:00:07.764 T:2458874064 DEBUG: CMMALRenderer::CheckConfigurationDeint Changing Deint dimensions from 160x64 (160x64) to 1920x1080 (1920x1088) S420->OPQV mode 33->28 bpp:8
16072019-12-06 09:00:07.765 T:2973264992 DEBUG: CMMALRenderer::RenderUpdate - vsync -1 (+13530)
16082019-12-06 09:00:07.765 T:2931814608 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0xae203240
16092019-12-06 09:00:07.767 T:2973264992 DEBUG: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 79
16102019-12-06 09:00:07.769 T:2931814608 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(19200), nBufferAlignment(16)
16112019-12-06 09:00:07.770 T:2931814608 DEBUG: CActiveAESink::OpenSink - SinkPi Initialized:
16122019-12-06 09:00:07.770 T:2931814608 DEBUG: Output Device : HDMI
16132019-12-06 09:00:07.770 T:2931814608 DEBUG: Sample Rate : 48000
16142019-12-06 09:00:07.770 T:2931814608 DEBUG: Sample Format : AE_FMT_FLOATP
16152019-12-06 09:00:07.771 T:2931814608 DEBUG: Channel Count : 2
16162019-12-06 09:00:07.771 T:2931814608 DEBUG: Channel Layout: FL, FR
16172019-12-06 09:00:07.771 T:2931814608 DEBUG: Frames : 2400
16182019-12-06 09:00:07.771 T:2931814608 DEBUG: Frame Size : 8
16192019-12-06 09:00:07.774 T:2942300368 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
16202019-12-06 09:00:07.776 T:2458874064 DEBUG: Previous line repeats 1 times.
16212019-12-06 09:00:07.776 T:2458874064 DEBUG: CMMALPool::Configure pool:0x9fb6b7b0 1920x1080 (1920x1088) pix:-1 size:3133440 fmt:I420
16222019-12-06 09:00:07.778 T:2492444880 DEBUG: CVideoPlayer::HandleMessages - player started 2
16232019-12-06 09:00:07.779 T:2768003280 DEBUG: OnAVChange: CApplication::OnAVChange
16242019-12-06 09:00:07.779 T:2492444880 DEBUG: CVideoPlayer::HandleMessages - player started 1
16252019-12-06 09:00:07.779 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnAVChange from xbmc
16262019-12-06 09:00:07.779 T:2492444880 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
16272019-12-06 09:00:07.779 T:2768003280 DEBUG: OnAVChange: CApplication::OnAVChange
16282019-12-06 09:00:07.779 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVChange
16292019-12-06 09:00:07.780 T:2492444880 DEBUG: VideoPlayer::Sync - Audio - pts: 1109333.000000, cache: 367135.756452, totalcache: 699999.988079
16302019-12-06 09:00:07.780 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnAVChange from xbmc
16312019-12-06 09:00:07.780 T:2492444880 DEBUG: VideoPlayer::Sync - Video - pts: 1621000.000000, cache: 50000.000000, totalcache: 100000.000000
16322019-12-06 09:00:07.780 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVChange
16332019-12-06 09:00:07.780 T:2500837584 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(9333.011921), level: 21, cache: 414405.367785
16342019-12-06 09:00:07.780 T:2500837584 DEBUG: CDVDAudio::Resume - resume audio stream
16352019-12-06 09:00:07.780 T:2942300368 DEBUG: ActiveAE - start sync of audio stream
16362019-12-06 09:00:07.781 T:2768003280 DEBUG: OnAVStarted: CApplication::OnAVStarted
16372019-12-06 09:00:07.781 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnAVStart from xbmc
16382019-12-06 09:00:07.781 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVStart
16392019-12-06 09:00:07.786 T:2973264992 DEBUG: Activating window ID: 12005
16402019-12-06 09:00:07.786 T:2973264992 DEBUG: ------ Window Deinit (Home.xml) ------
16412019-12-06 09:00:07.786 T:2973264992 DEBUG: FreeVisualisation() done
16422019-12-06 09:00:07.787 T:2973264992 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
16432019-12-06 09:00:07.789 T:2973264992 NOTICE: Whitelist search for: width: 1920, height: 1080, fps: 25.000, 3D: false
16442019-12-06 09:00:07.789 T:2973264992 DEBUG: Whitelist is empty using default one
16452019-12-06 09:00:07.789 T:2973264992 DEBUG: Trying to find exact refresh rate
16462019-12-06 09:00:07.789 T:2973264992 DEBUG: No exact whitelisted resolution matched, trying double refresh rate
16472019-12-06 09:00:07.789 T:2973264992 DEBUG: Matched fuzzy whitelisted Resolution 1920x1080 @ 50.000000 Hz (19)
16482019-12-06 09:00:07.789 T:2973264992 NOTICE: Display resolution ADJUST : 1920x1080 @ 50.000000 Hz (19) (weight: 0.000)
16492019-12-06 09:00:07.792 T:2973264992 DEBUG: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 62
16502019-12-06 09:00:07.794 T:2458874064 DEBUG: CMMALRenderer::CheckConfigurationVout Changing Vout dimensions from 0x0 (0x0) to 1920x1080 (1920x1088) I420
16512019-12-06 09:00:07.799 T:2458874064 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,1080 -> 0,0,1920,1080 (o:0 v:0 d:0 i:0)
16522019-12-06 09:00:07.799 T:2435821776 DEBUG: Thread MMALRenderer start, auto delete: false
16532019-12-06 09:00:07.799 T:2435821776 DEBUG: CMMALRenderer::Process - starting
16542019-12-06 09:00:07.799 T:2458874064 DEBUG: CMMALRenderer::SetVideoRect 0,0,1920,1080 -> 0,0,1920,1080 t:100000
16552019-12-06 09:00:07.807 T:2759610576 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
16562019-12-06 09:00:07.807 T:2759610576 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(9333.011921)
16572019-12-06 09:00:07.808 T:2475659472 DEBUG: CMMALPool::Configure pool:0x9a35bea0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
16582019-12-06 09:00:07.809 T:2768003280 DEBUG: OnAVChange: CApplication::OnAVChange
16592019-12-06 09:00:07.809 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnAVChange from xbmc
16602019-12-06 09:00:07.810 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVChange
16612019-12-06 09:00:07.836 T:2675683536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnAVChange - data: {"item":{"channeltype":"tv","id":17,"title":"ITV HD","type":"channel"},"player":{"playerid":1,"speed":1}}
16622019-12-06 09:00:07.836 T:2675683536 DEBUG: Previous line repeats 1 times.
16632019-12-06 09:00:07.836 T:2675683536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnAVStart - data: {"item":{"channeltype":"tv","id":17,"title":"ITV HD","type":"channel"},"player":{"playerid":1,"speed":1}}
16642019-12-06 09:00:07.836 T:2675683536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnAVChange - data: {"item":{"channeltype":"tv","id":17,"title":"ITV HD","type":"channel"},"player":{"playerid":1,"speed":1}}
16652019-12-06 09:00:07.924 T:2942300368 DEBUG: ActiveAE::SyncStream - average error of 686.991266, start adjusting
16662019-12-06 09:00:08.326 T:2759610576 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
16672019-12-06 09:00:08.574 T:2942300368 DEBUG: ActiveAE::SyncStream - average error 2.356297, last average error: 686.991266
16682019-12-06 09:00:08.674 T:2942300368 DEBUG: ActiveAE::SyncStream - average error of -47.682367, start adjusting
16692019-12-06 09:00:08.674 T:2942300368 DEBUG: ActiveAE::SyncStream - average error -0.015700 below threshold of 30.000000
16702019-12-06 09:00:08.845 T:2759610576 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
16712019-12-06 09:00:09.286 T:2458874064 DEBUG: CMMALRenderer::CheckConfigurationDeint Changing Deint dimensions from 1920x1080 (1920x1088) to 1920x1080 (1920x1088) OPQV->OPQV mode 28->27 bpp:8
16722019-12-06 09:00:09.300 T:2458874064 DEBUG: CMMALPool::Configure pool:0x9fb6b7b0 1920x1080 (1920x1088) pix:-1 size:3133440 fmt:I420
16732019-12-06 09:00:09.326 T:2475659472 DEBUG: CMMALPool::Configure pool:0x9a35bea0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
16742019-12-06 09:00:11.110 T:2492444880 DEBUG: Previous line repeats 42 times.
16752019-12-06 09:00:11.110 T:2492444880 DEBUG: CVideoPlayer::CheckContinuity - wrapback :2, prev:6552022.000000, curr:6461000.000000, diff:-91022.000000
16762019-12-06 09:00:11.137 T:2475659472 DEBUG: CMMALPool::Configure pool:0x9a35bea0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
16772019-12-06 09:00:11.793 T:2492444880 DEBUG: Previous line repeats 18 times.
16782019-12-06 09:00:11.793 T:2492444880 ERROR: Decode - avcodec_decode_subtitle returned failure
16792019-12-06 09:00:11.875 T:2475659472 DEBUG: CMMALPool::Configure pool:0x9a35bea0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
16802019-12-06 09:00:13.031 T:2500837584 DEBUG: Previous line repeats 29 times.
16812019-12-06 09:00:13.031 T:2500837584 ERROR: ffmpeg[950FC0D0]: [aac] skip_data_stream_element: Input buffer exhausted before END element found
16822019-12-06 09:00:13.062 T:2475659472 DEBUG: CMMALPool::Configure pool:0x9a35bea0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
16832019-12-06 09:00:13.078 T:2500837584 ERROR: ffmpeg[950FC0D0]: [aac] Number of bands (44) exceeds limit (40).
16842019-12-06 09:00:13.081 T:2500837584 ERROR: ffmpeg[950FC0D0]: [aac] Input buffer exhausted before END element found
16852019-12-06 09:00:13.137 T:2475659472 DEBUG: CMMALPool::Configure pool:0x9a35bea0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
16862019-12-06 09:00:13.177 T:2500837584 ERROR: ffmpeg[950FC0D0]: [aac] invalid band type
16872019-12-06 09:00:13.178 T:2500837584 ERROR: Previous line repeats 1 times.
16882019-12-06 09:00:13.179 T:2500837584 ERROR: ffmpeg[950FC0D0]: [aac] Number of bands (56) exceeds limit (40).
16892019-12-06 09:00:13.179 T:2500837584 ERROR: ffmpeg[950FC0D0]: [aac] Number of bands (50) exceeds limit (40).
16902019-12-06 09:00:13.179 T:2500837584 ERROR: ffmpeg[950FC0D0]: [aac] invalid band type
16912019-12-06 09:00:13.179 T:2500837584 ERROR: ffmpeg[950FC0D0]: [aac] Number of bands (41) exceeds limit (40).
16922019-12-06 09:00:13.180 T:2500837584 ERROR: ffmpeg[950FC0D0]: [aac] Number of bands (53) exceeds limit (40).
16932019-12-06 09:00:13.180 T:2475659472 DEBUG: CMMALPool::Configure pool:0x9a35bea0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
16942019-12-06 09:00:13.197 T:2492444880 DEBUG: Previous line repeats 1 times.
16952019-12-06 09:00:13.197 T:2492444880 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:-0.050000
16962019-12-06 09:00:13.222 T:2475659472 DEBUG: CMMALPool::Configure pool:0x9a35bea0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
16972019-12-06 09:00:13.378 T:2500837584 DEBUG: Previous line repeats 3 times.
16982019-12-06 09:00:13.378 T:2500837584 NOTICE: CVideoPlayerAudio::Process - stream stalled
16992019-12-06 09:00:13.398 T:2475659472 DEBUG: CMMALPool::Configure pool:0x9a35bea0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
17002019-12-06 09:00:13.398 T:2492444880 DEBUG: Stream stalled, start buffering. Audio: 0 - Video: 13
17012019-12-06 09:00:13.398 T:2492444880 DEBUG: CVideoPlayer::SetCaching - caching state 1
17022019-12-06 09:00:13.398 T:2492444880 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
17032019-12-06 09:00:13.398 T:2500837584 DEBUG: CDVDAudio::Pause - pausing audio stream
17042019-12-06 09:00:13.402 T:2475659472 DEBUG: CMMALPool::Configure pool:0x9a35bea0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
17052019-12-06 09:00:13.906 T:2759610576 DEBUG: Previous line repeats 1 times.
17062019-12-06 09:00:13.906 T:2759610576 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
17072019-12-06 09:00:15.907 T:2973264992 WARNING: Previous line repeats 3 times.
17082019-12-06 09:00:15.909 T:2973264992 DEBUG: CMMALRenderer::RenderUpdate - vsync 13935 (+1)
17092019-12-06 09:00:15.927 T:2759610576 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
17102019-12-06 09:00:37.810 T:2768003280 WARNING: Previous line repeats 42 times.
17112019-12-06 09:00:37.810 T:2768003280 DEBUG: Thread JobWorker 2768003280 terminating (autodelete)
17122019-12-06 09:00:38.105 T:2759610576 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
17132019-12-06 09:00:39.868 T:2526015696 WARNING: Previous line repeats 3 times.
17142019-12-06 09:00:39.868 T:2526015696 DEBUG: Thread JobWorker 2526015696 terminating (autodelete)
17152019-12-06 09:00:40.146 T:2759610576 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
17162019-12-06 09:01:13.534 T:2931814608 WARNING: Previous line repeats 64 times.
17172019-12-06 09:01:13.534 T:2931814608 DEBUG: CAESinkPi:Drain delay:99ms now:0ms
17182019-12-06 09:01:13.704 T:2759610576 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
17192019-12-06 09:01:22.437 T:2622275792 WARNING: Previous line repeats 16 times.
17202019-12-06 09:01:22.437 T:2622275792 DEBUG: PushCecKeypress - received key e0 duration 1000
17212019-12-06 09:01:22.445 T:2973264992 DEBUG: HandleKey: guide (0xe0) pressed, action is Stop
17222019-12-06 09:01:22.445 T:2973264992 NOTICE: CVideoPlayer::CloseFile()
17232019-12-06 09:01:22.445 T:2973264992 DEBUG: DeleteRenderer - deleting renderer
17242019-12-06 09:01:22.445 T:2973264992 DEBUG: CMMALRenderer::~CMMALRenderer
17252019-12-06 09:01:22.449 T:2973264992 DEBUG: CMMALRenderer::UnInitMMAL
17262019-12-06 09:01:22.465 T:2435821776 DEBUG: CMMALRenderer::Process - stopping
17272019-12-06 09:01:22.465 T:2435821776 DEBUG: Thread MMALRenderer 2435821776 terminating
17282019-12-06 09:01:22.489 T:2458874064 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x9fb6b7b0 for port vc.ril.image_fx:out:0(I420)
17292019-12-06 09:01:22.496 T:2458874064 DEBUG: CMMALRenderer::Run - stopping
17302019-12-06 09:01:22.496 T:2458874064 DEBUG: Thread MMALProcess 2458874064 terminating
17312019-12-06 09:01:22.496 T:2973264992 NOTICE: VideoPlayer: waiting for threads to exit
17322019-12-06 09:01:22.523 T:2492444880 NOTICE: CVideoPlayer::OnExit()
17332019-12-06 09:01:22.523 T:2492444880 DEBUG: CVideoPlayer::SetCaching - caching state 0
17342019-12-06 09:01:22.524 T:2492444880 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
17352019-12-06 09:01:22.524 T:2500837584 DEBUG: CDVDAudio::Resume - resume audio stream
17362019-12-06 09:01:22.524 T:2492444880 NOTICE: Closing stream player 1
17372019-12-06 09:01:22.524 T:2492444880 NOTICE: Waiting for audio thread to exit
17382019-12-06 09:01:22.524 T:2500837584 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
17392019-12-06 09:01:22.524 T:2931814608 NOTICE: CAESinkPi:AddPackets Underrun (delay:0.00 frames:2400)
17402019-12-06 09:01:22.524 T:2500837584 NOTICE: thread end: CVideoPlayerAudio::OnExit()
17412019-12-06 09:01:22.524 T:2500837584 DEBUG: Thread VideoPlayerAudio 2500837584 terminating
17422019-12-06 09:01:22.524 T:2492444880 NOTICE: Closing audio device
17432019-12-06 09:01:22.526 T:2492444880 DEBUG: CDVDAudio::Flush - flush audio stream
17442019-12-06 09:01:22.526 T:2942300368 DEBUG: CActiveAE::DiscardStream - audio stream deleted
17452019-12-06 09:01:22.527 T:2942300368 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
17462019-12-06 09:01:22.527 T:2492444880 DEBUG: Previous line repeats 2 times.
17472019-12-06 09:01:22.527 T:2492444880 NOTICE: Deleting audio codec
17482019-12-06 09:01:22.527 T:2492444880 NOTICE: Closing stream player 2
17492019-12-06 09:01:22.527 T:2492444880 NOTICE: waiting for video thread to exit
17502019-12-06 09:01:22.529 T:2931814608 INFO: CActiveAESink::OpenSink - initialize sink
17512019-12-06 09:01:22.545 T:2759610576 NOTICE: thread end: video_thread
17522019-12-06 09:01:22.545 T:2759610576 DEBUG: Thread VideoPlayerVideo 2759610576 terminating
17532019-12-06 09:01:22.546 T:2492444880 NOTICE: deleting video codec
17542019-12-06 09:01:22.549 T:2492444880 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x9a35bea0 for port vc.ril.video_decode:out:0(OPQV)
17552019-12-06 09:01:22.553 T:2492444880 NOTICE: Closing stream player 3
17562019-12-06 09:01:22.553 T:2475659472 DEBUG: Thread JobWorker start, auto delete: true
17572019-12-06 09:01:22.554 T:2492444880 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux unsubscribe from 1906726315
17582019-12-06 09:01:22.555 T:2492444880 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux successfully unsubscribed from channel id 1906726315, subscription id 2
17592019-12-06 09:01:22.555 T:2492444880 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux close
17602019-12-06 09:01:22.555 T:2492444880 DEBUG: CInputStreamPVRChannel - ClosePVRStream - closed channel stream pvr://channels/tv/All channels/pvr.hts_1906726315.pvr
17612019-12-06 09:01:22.555 T:2973264992 NOTICE: VideoPlayer: finished waiting
17622019-12-06 09:01:22.555 T:2973264992 NOTICE: CVideoPlayer::CloseFile()
17632019-12-06 09:01:22.556 T:2973264992 NOTICE: VideoPlayer: waiting for threads to exit
17642019-12-06 09:01:22.556 T:2973264992 NOTICE: VideoPlayer: finished waiting
17652019-12-06 09:01:22.556 T:2492444880 DEBUG: Thread VideoPlayer 2492444880 terminating
17662019-12-06 09:01:22.558 T:2973264992 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
17672019-12-06 09:01:22.566 T:2475659472 INFO: Deleting settings information for files pvr://channels/tv/All channels/pvr.hts_1906726315.pvr
17682019-12-06 09:01:22.566 T:2475659472 DEBUG: Mysql execute: DELETE FROM settings WHERE idFile=4664
17692019-12-06 09:01:22.567 T:2475659472 DEBUG: OnPlayBackStopped: CApplication::OnPlayBackStopped
17702019-12-06 09:01:22.567 T:2492444880 DEBUG: Thread JobWorker start, auto delete: true
17712019-12-06 09:01:22.575 T:2973249744 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
17722019-12-06 09:01:22.575 T:2973249744 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
17732019-12-06 09:01:22.579 T:2973264992 DEBUG: CMMALPool::~CMMALPool Destroying pool 0x9a0f96b0 for port vc.ril.video_decode:out:0(I420)
17742019-12-06 09:01:22.579 T:2973264992 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
17752019-12-06 09:01:22.580 T:2973264992 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
17762019-12-06 09:01:22.589 T:2973264992 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
17772019-12-06 09:01:22.589 T:2973264992 DEBUG: ------ Window Init (Home.xml) ------
17782019-12-06 09:01:22.589 T:2973264992 DEBUG: CDirectoryProvider[pvr://channels/tv/*?view=lastplayed]: refreshing..
17792019-12-06 09:01:22.590 T:2973264992 DEBUG: CDirectoryProvider[plugin://script.skin.helper.widgets/?action=next&mediatype=episodes&reload=]: refreshing..
17802019-12-06 09:01:22.590 T:2973264992 DEBUG: CDirectoryProvider[videodb://inprogresstvshows]: refreshing..
17812019-12-06 09:01:22.590 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_episodes.xsp]: refreshing..
17822019-12-06 09:01:22.590 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_tvshows.xsp]: refreshing..
17832019-12-06 09:01:22.590 T:2973264992 DEBUG: CDirectoryProvider[videodb://tvshows/genres/]: refreshing..
17842019-12-06 09:01:22.590 T:2973264992 DEBUG: CDirectoryProvider[videodb://tvshows/studios/]: refreshing..
17852019-12-06 09:01:22.590 T:2973264992 DEBUG: CDirectoryProvider[library://video/tvshows/]: refreshing..
17862019-12-06 09:01:22.590 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/inprogress_movies.xsp]: refreshing..
17872019-12-06 09:01:22.590 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_movies.xsp]: refreshing..
17882019-12-06 09:01:22.590 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_movies.xsp]: refreshing..
17892019-12-06 09:01:22.590 T:2973264992 DEBUG: CDirectoryProvider[special://skin/playlists/random_movies.xsp]: refreshing..
17902019-12-06 09:01:22.590 T:2973264992 DEBUG: CDirectoryProvider[videodb://movies/genres/]: refreshing..
17912019-12-06 09:01:22.590 T:2973264992 DEBUG: CDirectoryProvider[library://video/movies/]: refreshing..
17922019-12-06 09:01:22.591 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing..
17932019-12-06 09:01:22.591 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing..
17942019-12-06 09:01:22.591 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/game/]: refreshing..
17952019-12-06 09:01:22.591 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing..
17962019-12-06 09:01:22.591 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing..
17972019-12-06 09:01:22.591 T:2973264992 DEBUG: CDirectoryProvider[addons://]: refreshing..
17982019-12-06 09:01:22.591 T:2973264992 DEBUG: CDirectoryProvider[library://video/]: refreshing..
17992019-12-06 09:01:22.592 T:2973264992 DEBUG: CDirectoryProvider[sources://video/]: refreshing..
18002019-12-06 09:01:22.592 T:2973264992 DEBUG: CDirectoryProvider[special://videoplaylists/]: refreshing..
18012019-12-06 09:01:22.592 T:2973264992 DEBUG: CDirectoryProvider[pvr://channels/radio/*?view=lastplayed]: refreshing..
18022019-12-06 09:01:22.592 T:2973264992 DEBUG: CDirectoryProvider[favourites://]: refreshing..
18032019-12-06 09:01:22.592 T:2973264992 DEBUG: CDirectoryProvider[addons://sources/game/]: refreshing..
18042019-12-06 09:01:22.596 T:2475659472 DEBUG: StartScript - calling plugin Skin Helper Service Widgets('plugin://script.skin.helper.widgets/','3','?action=next&mediatype=episodes&reload=','resume:false')
18052019-12-06 09:01:22.596 T:2467266768 DEBUG: Thread LanguageInvoker start, auto delete: false
18062019-12-06 09:01:22.596 T:2467266768 INFO: initializing python engine.
18072019-12-06 09:01:22.596 T:2467266768 DEBUG: CPythonInvoker(4, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): start processing
18082019-12-06 09:01:22.598 T:2759610576 DEBUG: Thread JobWorker start, auto delete: true
18092019-12-06 09:01:22.625 T:2931814608 DEBUG: CAESinkPi:Drain delay:95ms now:0ms
18102019-12-06 09:01:22.625 T:2931814608 DEBUG: CAESinkPi:Deinitialize
18112019-12-06 09:01:22.628 T:2931814608 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000
18122019-12-06 09:01:22.631 T:2931814608 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0xae203240
18132019-12-06 09:01:22.632 T:2931814608 DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI
18142019-12-06 09:01:22.632 T:2931814608 DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:17640 bytes/s=352800.00 dest=PI:HDMI
18152019-12-06 09:01:22.632 T:2931814608 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008
18162019-12-06 09:01:22.634 T:2931814608 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0xae203240
18172019-12-06 09:01:22.637 T:2931814608 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(17648), nBufferAlignment(16)
18182019-12-06 09:01:22.638 T:2931814608 DEBUG: CActiveAESink::OpenSink - SinkPi Initialized:
18192019-12-06 09:01:22.638 T:2931814608 DEBUG: Output Device : HDMI
18202019-12-06 09:01:22.638 T:2931814608 DEBUG: Sample Rate : 44100
18212019-12-06 09:01:22.638 T:2931814608 DEBUG: Sample Format : AE_FMT_FLOAT
18222019-12-06 09:01:22.638 T:2931814608 DEBUG: Channel Count : 2
18232019-12-06 09:01:22.638 T:2931814608 DEBUG: Channel Layout: FL, FR
18242019-12-06 09:01:22.638 T:2931814608 DEBUG: Frames : 2205
18252019-12-06 09:01:22.638 T:2931814608 DEBUG: Frame Size : 8
18262019-12-06 09:01:22.639 T:2942300368 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
18272019-12-06 09:01:22.719 T:2675683536 DEBUG: Previous line repeats 1 times.
18282019-12-06 09:01:22.719 T:2675683536 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnStop - data: {"end":false,"item":{"channeltype":"tv","id":17,"title":"ITV HD","type":"channel"}}
18292019-12-06 09:01:22.772 T:2467266768 DEBUG: -->Python Interpreter Initialized<--
18302019-12-06 09:01:22.772 T:2467266768 DEBUG: CPythonInvoker(4, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): the source file to load is "/home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py"
18312019-12-06 09:01:22.774 T:2467266768 DEBUG: CPythonInvoker(4, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): setting the Python path to /home/pi/.kodi/addons/script.skin.helper.widgets:/home/pi/.kodi/addons/script.module.arrow/lib:/home/pi/.kodi/addons/script.module.beautifulsoup/lib:/home/pi/.kodi/addons/script.module.certifi/lib:/home/pi/.kodi/addons/script.module.chardet/lib:/home/pi/.kodi/addons/script.module.dateutil/lib:/home/pi/.kodi/addons/script.module.idna/lib:/home/pi/.kodi/addons/script.module.metadatautils/lib:/home/pi/.kodi/addons/script.module.musicbrainz/lib:/home/pi/.kodi/addons/script.module.requests/lib:/home/pi/.kodi/addons/script.module.simplecache/lib:/home/pi/.kodi/addons/script.module.simplejson/lib:/home/pi/.kodi/addons/script.module.six/lib:/home/pi/.kodi/addons/script.module.thetvdb/lib:/home/pi/.kodi/addons/script.module.urllib3/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-arm-linux-gnueabihf:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages
18322019-12-06 09:01:22.774 T:2467266768 DEBUG: CPythonInvoker(4, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): entering source directory /home/pi/.kodi/addons/script.skin.helper.widgets
18332019-12-06 09:01:22.774 T:2467266768 DEBUG: CPythonInvoker(4, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): instantiating addon using automatically obtained id of "script.skin.helper.widgets" dependent on version 2.24.0 of the xbmc.python api
18342019-12-06 09:01:22.893 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording I'm A Celebrity...Get Me...
18352019-12-06 09:01:22.893 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording EastEnders
18362019-12-06 09:01:22.894 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording New: I'm A Celebrity...Get Me...
18372019-12-06 09:01:22.894 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording The Sheriffs Are Coming
18382019-12-06 09:01:22.895 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording New: I'm A Celebrity...Get Me...
18392019-12-06 09:01:22.895 T:2973264992 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Getting play position 0 for recording EastEnders
18402019-12-06 09:01:23.036 T:2973264992 DEBUG: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 62
18412019-12-06 09:01:23.075 T:2973264992 DEBUG: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 79
18422019-12-06 09:01:23.263 T:2759610576 DEBUG: CAddonMgr::GetAvailableUpdates took 30 ms
18432019-12-06 09:01:23.292 T:2759610576 DEBUG: NFS: Context for 192.168.1.16/media/2TB not open - get a new context.
18442019-12-06 09:01:23.298 T:2492444880 DEBUG: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
18452019-12-06 09:01:23.298 T:2492444880 DEBUG: Getting Directory (special://profile/playlists/video)
18462019-12-06 09:01:23.299 T:2492444880 DEBUG: Getting Directory (special://profile/playlists/mixed)
18472019-12-06 09:01:23.299 T:2492444880 DEBUG: CMultiPathDirectory::MergeItems, items = 0
18482019-12-06 09:01:23.302 T:2759610576 DEBUG: NFS: Connected to server 192.168.1.16 and export /media/2TB
18492019-12-06 09:01:23.302 T:2759610576 DEBUG: NFS: chunks: r/w 65536/65536
18502019-12-06 09:01:23.305 T:2759610576 DEBUG: NFS: Context for 192.168.1.16/media/3TB not open - get a new context.
18512019-12-06 09:01:23.311 T:2492444880 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
18522019-12-06 09:01:23.315 T:2759610576 DEBUG: NFS: Connected to server 192.168.1.16 and export /media/3TB
18532019-12-06 09:01:23.315 T:2759610576 DEBUG: NFS: chunks: r/w 65536/65536
18542019-12-06 09:01:23.318 T:2492444880 DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.*, songartistview.* FROM (SELECT idAlbum FROM album ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum = recentalbums.idAlbum JOIN songartistview ON songview.idSong = songartistview.idSong ORDER BY songview.idAlbum DESC, songview.idSong, songartistview.idRole, songartistview.iOrder
18552019-12-06 09:01:23.318 T:2759610576 DEBUG: NFS: Context for 192.168.1.16/media/4TB not open - get a new context.
18562019-12-06 09:01:23.320 T:2492444880 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
18572019-12-06 09:01:23.323 T:2492444880 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
18582019-12-06 09:01:23.330 T:2759610576 DEBUG: NFS: Connected to server 192.168.1.16 and export /media/4TB
18592019-12-06 09:01:23.330 T:2759610576 DEBUG: NFS: chunks: r/w 65536/65536
18602019-12-06 09:01:23.339 T:2759610576 DEBUG: NFS: Refreshing context for 192.168.1.16/media/2TB, old: 350408, new: 350455
18612019-12-06 09:01:23.339 T:2759610576 DEBUG: NFS: Using cached context.
18622019-12-06 09:01:23.340 T:2759610576 DEBUG: NFS: Refreshing context for 192.168.1.16/media/3TB, old: 350421, new: 350456
18632019-12-06 09:01:23.340 T:2759610576 DEBUG: NFS: Using cached context.
18642019-12-06 09:01:23.342 T:2759610576 DEBUG: NFS: Refreshing context for 192.168.1.16/media/4TB, old: 350434, new: 350458
18652019-12-06 09:01:23.342 T:2759610576 DEBUG: NFS: Using cached context.
18662019-12-06 09:01:23.371 T:2492444880 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
18672019-12-06 09:01:23.374 T:2492444880 DEBUG: GetArtistsByWhere query: SELECT COUNT(DISTINCT artistview.idArtist) FROM artistview WHERE (EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artistview.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '')
18682019-12-06 09:01:23.999 T:2467266768 DEBUG: Skin Helper Simplecache --> Initialized
18692019-12-06 09:01:24.000 T:2467266768 DEBUG: CAddonSettings[script.module.metadatautils]: loading setting definitions
18702019-12-06 09:01:24.000 T:2467266768 DEBUG: CAddonSettings[script.module.metadatautils]: trying to load setting definitions from old format...
18712019-12-06 09:01:24.001 T:2467266768 DEBUG: Skin Helper Simplecache --> Initialized
18722019-12-06 09:01:24.002 T:2467266768 DEBUG: script.module.thetvdb --> Initialized
18732019-12-06 09:01:24.002 T:2467266768 DEBUG: Skin Helper Simplecache --> Initialized
18742019-12-06 09:01:24.066 T:2467266768 DEBUG: Previous line repeats 1 times.
18752019-12-06 09:01:24.066 T:2467266768 DEBUG: CAddonSettings[script.module.metadatautils]: loading setting definitions
18762019-12-06 09:01:24.066 T:2467266768 DEBUG: CAddonSettings[script.module.metadatautils]: trying to load setting definitions from old format...
18772019-12-06 09:01:24.067 T:2467266768 DEBUG: Skin Helper Simplecache --> Initialized
18782019-12-06 09:01:24.067 T:2467266768 DEBUG: Metadata and Artwork module --> Initialized
18792019-12-06 09:01:24.067 T:2467266768 DEBUG: Skin Helper Simplecache --> Initialized
18802019-12-06 09:01:24.068 T:2467266768 DEBUG: CAddonSettings[script.skin.helper.widgets]: loading setting definitions
18812019-12-06 09:01:24.068 T:2467266768 DEBUG: CAddonSettings[script.skin.helper.widgets]: trying to load setting definitions from old format...
18822019-12-06 09:01:24.075 T:2467266768 DEBUG: Skin Helper Widgets --> MEDIATYPE: episodes - ACTION: next -- got items from cache - CHECKSUM: action.nextepisodes_enable_specials.Falsegroup_episodes.Falsehide_watched.Falselimit.25mediatype.episodesnext_inprogress_only.True
18832019-12-06 09:01:24.105 T:2442088656 ERROR: NEWADDON Unknown Video Info Key "progress"
18842019-12-06 09:01:24.180 T:2467266768 ERROR: Previous line repeats 3 times.
18852019-12-06 09:01:24.180 T:2467266768 DEBUG: Skin Helper Simplecache --> Closed
18862019-12-06 09:01:24.180 T:2467266768 DEBUG: Previous line repeats 4 times.
18872019-12-06 09:01:24.180 T:2467266768 DEBUG: Metadata and Artwork module --> Exited
18882019-12-06 09:01:24.180 T:2467266768 DEBUG: Skin Helper Simplecache --> Closed
18892019-12-06 09:01:24.181 T:2467266768 DEBUG: Skin Helper Widgets --> MainModule exited
18902019-12-06 09:01:24.181 T:2467266768 INFO: CPythonInvoker(4, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py): script successfully run
18912019-12-06 09:01:24.181 T:2467266768 DEBUG: onExecutionDone(4, /home/pi/.kodi/addons/script.skin.helper.widgets/plugin.py)
18922019-12-06 09:01:24.198 T:2467266768 INFO: Python interpreter stopped
18932019-12-06 09:01:24.199 T:2467266768 DEBUG: Thread LanguageInvoker 2467266768 terminating
18942019-12-06 09:01:53.345 T:2759610576 DEBUG: Thread JobWorker 2759610576 terminating (autodelete)
18952019-12-06 09:01:53.382 T:2492444880 DEBUG: Thread JobWorker 2492444880 terminating (autodelete)
18962019-12-06 09:01:54.190 T:2475659472 DEBUG: Thread JobWorker 2475659472 terminating (autodelete)