· 6 years ago · Mar 11, 2020, 03:44 AM
12020-03-10 21:53:43.487 T:4092502032 NOTICE: -----------------------------------------------------------------------
22020-03-10 21:53:43.487 T:4092502032 NOTICE: Starting Kodi (18.6 Git:bb42ff2656747890980bf779488ec748a05c601e). Platform: Linux ARM 32-bit
32020-03-10 21:53:43.487 T:4092502032 NOTICE: Using Release Kodi x32 build
42020-03-10 21:53:43.487 T:4092502032 NOTICE: Kodi compiled 2020-03-05 by GCC 8.3.0 for Linux ARM 32-bit version 3.14.29 (200221)
52020-03-10 21:53:43.487 T:4092502032 NOTICE: Running on CoreELEC (official): nightly_20200304 (9.2), kernel: Linux ARM 64-bit version 3.14.29 aarch64
62020-03-10 21:53:43.487 T:4092502032 NOTICE: FFmpeg version/source: 4.0.4-Kodi
72020-03-10 21:53:43.487 T:4092502032 NOTICE: Host CPU: Amlogic S905 rev c, 4 cores available
82020-03-10 21:53:43.487 T:4092502032 NOTICE: ARM Features: Neon enabled
92020-03-10 21:53:43.487 T:4092502032 NOTICE: special://xbmc/ is mapped to: /usr/share/kodi/
102020-03-10 21:53:43.487 T:4092502032 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/kodi
112020-03-10 21:53:43.488 T:4092502032 NOTICE: special://xbmcbinaddons/ is mapped to: /usr/lib/kodi/addons
122020-03-10 21:53:43.488 T:4092502032 NOTICE: special://masterprofile/ is mapped to: /storage/.kodi/userdata
132020-03-10 21:53:43.488 T:4092502032 NOTICE: special://envhome/ is mapped to: /storage
142020-03-10 21:53:43.488 T:4092502032 NOTICE: special://home/ is mapped to: /storage/.kodi
152020-03-10 21:53:43.488 T:4092502032 NOTICE: special://temp/ is mapped to: /storage/.kodi/temp
162020-03-10 21:53:43.488 T:4092502032 NOTICE: special://logpath/ is mapped to: /storage/.kodi/temp
172020-03-10 21:53:43.488 T:4092502032 NOTICE: The executable running is: /usr/lib/kodi/kodi.bin
182020-03-10 21:53:43.488 T:4092502032 NOTICE: Local hostname: CoreELEC
192020-03-10 21:53:43.488 T:4092502032 NOTICE: Log File is located: /storage/.kodi/temp/kodi.log
202020-03-10 21:53:43.488 T:4092502032 NOTICE: -----------------------------------------------------------------------
212020-03-10 21:53:43.490 T:4092502032 INFO: loading settings
222020-03-10 21:53:43.495 T:4092502032 NOTICE: special://profile/ is mapped to: special://masterprofile/
232020-03-10 21:53:43.523 T:4092502032 DEBUG: CSkinSettings: no <skinsettings> tag found
242020-03-10 21:53:43.524 T:4092502032 NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml
252020-03-10 21:53:43.524 T:4092502032 NOTICE: Contents of special://xbmc/system/advancedsettings.xml are...
26 <?xml version="1.0" encoding="utf-8" ?>
27 <advancedsettings>
28 <cputempcommand>/usr/bin/cputemp</cputempcommand>
29 <gputempcommand>/usr/bin/gputemp</gputempcommand>
30 <showexitbutton>false</showexitbutton>
31 <remotedelay>1</remotedelay>
32 <samba>
33 <clienttimeout>30</clienttimeout>
34 </samba>
35 <splash>false</splash>
36 </advancedsettings>
372020-03-10 21:53:43.524 T:4092502032 WARNING: CSettingsManager: missing version attribute
382020-03-10 21:53:43.525 T:4092502032 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
392020-03-10 21:53:43.525 T:4092502032 NOTICE: Default Video Player: VideoPlayer
402020-03-10 21:53:43.525 T:4092502032 NOTICE: Default Audio Player: paplayer
412020-03-10 21:53:43.526 T:4092502032 NOTICE: Enabled debug logging due to GUI setting (2)
422020-03-10 21:53:43.526 T:4092502032 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM"
432020-03-10 21:53:43.526 T:4092502032 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
442020-03-10 21:53:43.539 T:4092502032 INFO: creating subdirectories
452020-03-10 21:53:43.539 T:4092502032 INFO: userdata folder: special://masterprofile/
462020-03-10 21:53:43.539 T:4092502032 INFO: recording folder:
472020-03-10 21:53:43.539 T:4092502032 INFO: screenshots folder: /storage/screenshots/
482020-03-10 21:53:43.552 T:4092502032 DEBUG: libinput: event3: device is ignored
492020-03-10 21:53:43.707 T:4092502032 NOTICE: PulseAudio: Server found running - will try to use Pulse
502020-03-10 21:53:43.709 T:4014990208 DEBUG: Thread Lirc start, auto delete: false
512020-03-10 21:53:43.710 T:4082103168 DEBUG: Thread libinput start, auto delete: false
522020-03-10 21:53:43.710 T:4014990208 INFO: Loading special://xbmc/system/Lircmap.xml
532020-03-10 21:53:43.717 T:4092502032 NOTICE: Running database version Addons27
542020-03-10 21:53:43.726 T:4014990208 INFO: * Adding remote mapping for device 'mceusb'
552020-03-10 21:53:43.727 T:4014990208 INFO: * Adding remote mapping for device 'XboxDVDDongle'
562020-03-10 21:53:43.727 T:4014990208 INFO: * Adding remote mapping for device 'Microsoft_Xbox'
572020-03-10 21:53:43.727 T:4014990208 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
582020-03-10 21:53:43.727 T:4014990208 INFO: * Adding remote mapping for device 'anysee'
592020-03-10 21:53:43.728 T:4014990208 INFO: * Adding remote mapping for device 'iMON-PAD'
602020-03-10 21:53:43.728 T:4014990208 INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
612020-03-10 21:53:43.728 T:4014990208 INFO: * Adding remote mapping for device 'MCE_via_iMON'
622020-03-10 21:53:43.728 T:4014990208 INFO: * Adding remote mapping for device 'TwinHanRemote'
632020-03-10 21:53:43.729 T:4014990208 INFO: * Adding remote mapping for device 'linux-input-layer'
642020-03-10 21:53:43.729 T:4014990208 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
652020-03-10 21:53:43.729 T:4014990208 INFO: * Adding remote mapping for device 'mediacenter'
662020-03-10 21:53:43.729 T:4014990208 INFO: * Adding remote mapping for device 'devinput'
672020-03-10 21:53:43.730 T:4014990208 INFO: * Adding remote mapping for device 'devinput-32'
682020-03-10 21:53:43.730 T:4014990208 INFO: * Adding remote mapping for device 'devinput-64'
692020-03-10 21:53:43.732 T:4014990208 DEBUG: CIRTranslator::Load - no userdata Lircmap.xml found, skipping
702020-03-10 21:53:43.800 T:4092502032 INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/lib/kodi/addons: No such file or directory'
712020-03-10 21:53:43.800 T:4092502032 DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
722020-03-10 21:53:43.800 T:4092502032 NOTICE: ADDON: audioencoder.kodi.builtin.aac v1.0.0 installed
732020-03-10 21:53:43.800 T:4092502032 NOTICE: ADDON: audioencoder.kodi.builtin.wma v1.0.0 installed
742020-03-10 21:53:43.800 T:4092502032 NOTICE: ADDON: driver.dvb.crazycat_aml v9.2.0 installed
752020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: driver.dvb.dvb-latest v9.2.0 installed
762020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: game.controller.default v1.0.8 installed
772020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: game.controller.snes v1.0.8 installed
782020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.binary.global.audioengine v1.0.1 installed
792020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.binary.global.filesystem v1.0.2 installed
802020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.binary.global.general v1.0.3 installed
812020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.binary.global.gui v5.12.0 installed
822020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.binary.global.main v1.0.14 installed
832020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.binary.global.network v1.0.0 installed
842020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.binary.instance.audiodecoder v2.0.0 installed
852020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.binary.instance.audioencoder v2.0.0 installed
862020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.binary.instance.game v1.1.0 installed
872020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.binary.instance.imagedecoder v2.0.0 installed
882020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.binary.instance.inputstream v2.0.8 installed
892020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.binary.instance.peripheral v1.3.7 installed
902020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.binary.instance.pvr v5.10.3 installed
912020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.binary.instance.screensaver v2.0.0 installed
922020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.binary.instance.vfs v2.0.0 installed
932020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.binary.instance.videocodec v1.0.1 installed
942020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.binary.instance.visualization v2.0.1 installed
952020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: kodi.resource v1.0.0 installed
962020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: metadata.album.universal v3.1.2 installed
972020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: metadata.artists.universal v4.3.2 installed
982020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: metadata.common.allmusic.com v3.2.1 installed
992020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: metadata.common.fanart.tv v3.6.2 installed
1002020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: metadata.common.imdb.com v3.1.4 installed
1012020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: metadata.common.musicbrainz.org v2.2.1 installed
1022020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: metadata.common.theaudiodb.com v2.0.3 installed
1032020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: metadata.common.themoviedb.org v3.1.10 installed
1042020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: metadata.local v1.0.0 installed
1052020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: metadata.themoviedb.org v5.1.10 installed
1062020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: metadata.tvdb.com v3.2.4 installed
1072020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: metadata.tvshows.themoviedb.org v3.5.0 installed
1082020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: os.libreelec.tv v9.2 installed
1092020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: os.openelec.tv v9.2 installed
1102020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: peripheral.joystick v1.4.9 installed
1112020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: repository.cenightly v9.2 installed
1122020-03-10 21:53:43.801 T:4092502032 NOTICE: ADDON: repository.xbmc.org v9.9.0 installed
1132020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: resource.images.weathericons.default v1.1.8 installed
1142020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: resource.language.en_gb v2.0.1 installed
1152020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: resource.uisounds.kodi v1.0.0 installed
1162020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: screensaver.xbmc.builtin.black v1.0.33 installed
1172020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: screensaver.xbmc.builtin.dim v1.0.59 installed
1182020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: script.amlogic.displayinfo v9.2.100 installed
1192020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: script.module.pil v1.1.7 installed
1202020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: script.module.pycryptodome v3.4.3 installed
1212020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: script.program.driverselect v0.1.6.1 installed
1222020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: service.coreelec.settings v9.0.0 installed
1232020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: skin.estuary v2.0.27 installed
1242020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: webinterface.default v18.x-2.4.6 installed
1252020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: xbmc.addon v18.6.0 installed
1262020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: xbmc.core v0.1.0 installed
1272020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: xbmc.gui v5.14.0 installed
1282020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: xbmc.json v10.3.0 installed
1292020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: xbmc.metadata v2.1.0 installed
1302020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: xbmc.python v2.26.0 installed
1312020-03-10 21:53:43.802 T:4092502032 NOTICE: ADDON: xbmc.webinterface v1.0.0 installed
1322020-03-10 21:53:43.835 T:4092502032 DEBUG: CFavourites::Load - no system favourites found, skipping
1332020-03-10 21:53:43.835 T:4092502032 DEBUG: CFavourites::Load - no userdata favourites found, skipping
1342020-03-10 21:53:43.838 T:4092502032 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
1352020-03-10 21:53:43.838 T:4092502032 INFO: Selected Logind/UPower as PowerSyscall
1362020-03-10 21:53:43.839 T:4092502032 ERROR: DBus error: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files
1372020-03-10 21:53:43.839 T:4092502032 INFO: LogindUPowerSyscall - UPower not found, battery information will not be available
1382020-03-10 21:53:43.845 T:4092502032 DEBUG: LogindUPowerSyscall - inhibit lock taken, fd 20
1392020-03-10 21:53:43.847 T:4092502032 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
1402020-03-10 21:53:43.848 T:4069430144 DEBUG: Thread ActiveAE start, auto delete: false
1412020-03-10 21:53:43.848 T:4059034496 DEBUG: Thread AESink start, auto delete: false
1422020-03-10 21:53:43.848 T:4048548736 DEBUG: Thread FDEventMonitor start, auto delete: false
1432020-03-10 21:53:43.872 T:4069430144 DEBUG: CAESinkALSA - Unable to obtain ELD information for device "hdmi:CARD=AMLM8AUDIO,DEV=0" (not supported by device, or kernel older than 3.2)
1442020-03-10 21:53:43.874 T:4069430144 DEBUG: PulseAudio: Context authorizing
1452020-03-10 21:53:43.875 T:4069430144 DEBUG: PulseAudio: Context setting name
1462020-03-10 21:53:43.875 T:4069430144 DEBUG: PulseAudio: Context ready
1472020-03-10 21:53:43.875 T:4069430144 WARNING: Pulseaudio module module-allow-passthrough not loaded - opening PT devices might fail
1482020-03-10 21:53:43.876 T:4069430144 NOTICE: Found 2 Lists of Devices
1492020-03-10 21:53:43.876 T:4069430144 NOTICE: Enumerated ALSA devices:
1502020-03-10 21:53:43.876 T:4069430144 NOTICE: Device 1
1512020-03-10 21:53:43.876 T:4069430144 NOTICE: m_deviceName : default
1522020-03-10 21:53:43.876 T:4069430144 NOTICE: m_displayName : Default (AML-M8AUDIO Analog)
1532020-03-10 21:53:43.876 T:4069430144 NOTICE: m_displayNameExtra: PCM
1542020-03-10 21:53:43.876 T:4069430144 NOTICE: m_deviceType : AE_DEVTYPE_PCM
1552020-03-10 21:53:43.877 T:4069430144 NOTICE: m_channels : FL, FR, UNKNOWN1, LFE, BL, BR, FC, BC, BLOC, BROC
1562020-03-10 21:53:43.877 T:4069430144 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000
1572020-03-10 21:53:43.877 T:4069430144 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE
1582020-03-10 21:53:43.877 T:4069430144 NOTICE: m_streamTypes : No passthrough capabilities
1592020-03-10 21:53:43.877 T:4069430144 NOTICE: Device 2
1602020-03-10 21:53:43.877 T:4069430144 NOTICE: m_deviceName : sysdefault:CARD=AMLM8AUDIO
1612020-03-10 21:53:43.877 T:4069430144 NOTICE: m_displayName : AML-M8AUDIO
1622020-03-10 21:53:43.877 T:4069430144 NOTICE: m_displayNameExtra: PCM
1632020-03-10 21:53:43.877 T:4069430144 NOTICE: m_deviceType : AE_DEVTYPE_PCM
1642020-03-10 21:53:43.877 T:4069430144 NOTICE: m_channels : FL, FR, UNKNOWN1, LFE, BL, BR, FC, BC, BLOC, BROC
1652020-03-10 21:53:43.877 T:4069430144 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000
1662020-03-10 21:53:43.877 T:4069430144 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE
1672020-03-10 21:53:43.877 T:4069430144 NOTICE: m_streamTypes : No passthrough capabilities
1682020-03-10 21:53:43.877 T:4069430144 NOTICE: Device 3
1692020-03-10 21:53:43.877 T:4069430144 NOTICE: m_deviceName : hdmi:CARD=AMLM8AUDIO,DEV=0
1702020-03-10 21:53:43.877 T:4069430144 NOTICE: m_displayName : AML-M8AUDIO
1712020-03-10 21:53:43.877 T:4069430144 NOTICE: m_displayNameExtra: HDMI
1722020-03-10 21:53:43.877 T:4069430144 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
1732020-03-10 21:53:43.877 T:4069430144 NOTICE: m_channels : FL, FR, BL, BR, FC, LFE, SL, SR
1742020-03-10 21:53:43.877 T:4069430144 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000
1752020-03-10 21:53:43.877 T:4069430144 NOTICE: m_dataFormats : AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW
1762020-03-10 21:53:43.877 T:4069430144 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD,STREAM_TYPE_DTSHD_MA,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512,STREAM_TYPE_EAC3,STREAM_TYPE_TRUEHD
1772020-03-10 21:53:43.877 T:4069430144 NOTICE: Enumerated PULSE devices:
1782020-03-10 21:53:43.877 T:4069430144 NOTICE: Device 1
1792020-03-10 21:53:43.877 T:4069430144 NOTICE: m_deviceName : Default
1802020-03-10 21:53:43.877 T:4069430144 NOTICE: m_displayName : Default
1812020-03-10 21:53:43.877 T:4069430144 NOTICE: m_displayNameExtra: Bluetooth Audio (PULSEAUDIO)
1822020-03-10 21:53:43.877 T:4069430144 NOTICE: m_deviceType : AE_DEVTYPE_PCM
1832020-03-10 21:53:43.877 T:4069430144 NOTICE: m_channels : FL, FR
1842020-03-10 21:53:43.877 T:4069430144 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
1852020-03-10 21:53:43.877 T:4069430144 NOTICE: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT
1862020-03-10 21:53:43.877 T:4069430144 NOTICE: m_streamTypes : No passthrough capabilities
1872020-03-10 21:53:43.878 T:4059034496 INFO: CActiveAESink::OpenSink - initialize sink
1882020-03-10 21:53:43.878 T:4059034496 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:default
1892020-03-10 21:53:43.878 T:4059034496 INFO: CAESinkALSA::Initialize - Requested layout: FL, FR
1902020-03-10 21:53:43.878 T:4059034496 INFO: CAESinkALSA::Initialize - Attempting to open device "default"
1912020-03-10 21:53:43.882 T:4059034496 INFO: CAESinkALSA::Initialize - Opened device "default"
1922020-03-10 21:53:43.883 T:4059034496 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
1932020-03-10 21:53:43.883 T:4059034496 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
1942020-03-10 21:53:43.883 T:4059034496 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 4096, bufferSize 16384
1952020-03-10 21:53:43.888 T:4059034496 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 4096, bufferSize 16384
1962020-03-10 21:53:43.888 T:4059034496 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 372 ms
1972020-03-10 21:53:43.888 T:4059034496 INFO: CAESinkALSA::Initialize - setting default aud_ch to 0
1982020-03-10 21:53:43.888 T:4059034496 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
1992020-03-10 21:53:43.888 T:4059034496 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR
2002020-03-10 21:53:43.888 T:4059034496 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: none) (CEA: 0)
2012020-03-10 21:53:43.888 T:4059034496 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
2022020-03-10 21:53:43.888 T:4059034496 DEBUG: Output Device : Default (AML-M8AUDIO Analog)
2032020-03-10 21:53:43.888 T:4059034496 DEBUG: Sample Rate : 44100
2042020-03-10 21:53:43.888 T:4059034496 DEBUG: Sample Format : AE_FMT_S32NE
2052020-03-10 21:53:43.888 T:4059034496 DEBUG: Channel Count : 2
2062020-03-10 21:53:43.888 T:4059034496 DEBUG: Channel Layout: FL, FR
2072020-03-10 21:53:43.888 T:4059034496 DEBUG: Frames : 4096
2082020-03-10 21:53:43.888 T:4059034496 DEBUG: Frame Size : 8
2092020-03-10 21:53:43.898 T:4092502032 INFO: CKeyboardLayoutManager: loading keyboard layouts from special://xbmc/system/keyboardlayouts...
2102020-03-10 21:53:43.900 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Arabic QWERTY" successfully loaded
2112020-03-10 21:53:43.901 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian ЯВЕРТЪ" successfully loaded
2122020-03-10 21:53:43.902 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian АБВ" successfully loaded
2132020-03-10 21:53:43.903 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BasePY" successfully loaded
2142020-03-10 21:53:43.903 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BaiduPY" successfully loaded
2152020-03-10 21:53:43.904 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Croatian QWERTY" successfully loaded
2162020-03-10 21:53:43.904 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Czech QWERTZ" successfully loaded
2172020-03-10 21:53:43.905 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Danish QWERTY" successfully loaded
2182020-03-10 21:53:43.906 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "English QWERTY" successfully loaded
2192020-03-10 21:53:43.906 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "English AZERTY" successfully loaded
2202020-03-10 21:53:43.907 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "English ABC" successfully loaded
2212020-03-10 21:53:43.907 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "French AZERTY" successfully loaded
2222020-03-10 21:53:43.908 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "German QWERTZ" successfully loaded
2232020-03-10 21:53:43.909 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "German ABC" successfully loaded
2242020-03-10 21:53:43.909 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Greek QWERTY" successfully loaded
2252020-03-10 21:53:43.910 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew QWERTY" successfully loaded
2262020-03-10 21:53:43.910 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew ABC" successfully loaded
2272020-03-10 21:53:43.911 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Hungarian QWERTZ" successfully loaded
2282020-03-10 21:53:43.911 T:4082103168 DEBUG: CLibInputHandler::DeviceAdded - keyboard type device added: Dell Dell USB Keyboard (event2)
2292020-03-10 21:53:43.911 T:4082103168 DEBUG: CLibInputKeyboard::GetRepeat - delay: 500ms repeat: 33ms for Dell Dell USB Keyboard (event2)
2302020-03-10 21:53:43.912 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Icelandic QWERTY" successfully loaded
2312020-03-10 21:53:43.913 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Italian QWERTY" successfully loaded
2322020-03-10 21:53:43.913 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Korean ㄱㄴㄷ" successfully loaded
2332020-03-10 21:53:43.914 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian AZERTY" successfully loaded
2342020-03-10 21:53:43.915 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian QWERTY" successfully loaded
2352020-03-10 21:53:43.915 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Norwegian QWERTY" successfully loaded
2362020-03-10 21:53:43.916 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Polish QWERTY" successfully loaded
2372020-03-10 21:53:43.917 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Portuguese (Portugal) QWERTY" successfully loaded
2382020-03-10 21:53:43.917 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Romanian QWERTY" successfully loaded
2392020-03-10 21:53:43.918 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian ЙЦУКЕН" successfully loaded
2402020-03-10 21:53:43.919 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian АБВ" successfully loaded
2412020-03-10 21:53:43.919 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Silesian QWERTY" successfully loaded
2422020-03-10 21:53:43.920 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTZ" successfully loaded
2432020-03-10 21:53:43.921 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTY" successfully loaded
2442020-03-10 21:53:43.921 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak ABC" successfully loaded
2452020-03-10 21:53:43.922 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovenian QWERTZ" successfully loaded
2462020-03-10 21:53:43.923 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Spanish QWERTY" successfully loaded
2472020-03-10 21:53:43.923 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Swedish QWERTY" successfully loaded
2482020-03-10 21:53:43.924 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Thai Kedmanee" successfully loaded
2492020-03-10 21:53:43.925 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Turkish QWERTY" successfully loaded
2502020-03-10 21:53:43.925 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian ЙЦУКЕН" successfully loaded
2512020-03-10 21:53:43.926 T:4092502032 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian АБВ" successfully loaded
2522020-03-10 21:53:43.927 T:4092502032 DEBUG: DBus method call to org.freedesktop.DBus.Peer.Ping at /org/freedesktop/UDisks2 of org.freedesktop.UDisks2 failed: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UDisks2 was not provided by any .service files
2532020-03-10 21:53:43.927 T:4092502032 DEBUG: DBus method call to org.freedesktop.UDisks.EnumerateDevices at /org/freedesktop/UDisks of org.freedesktop.UDisks failed: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UDisks was not provided by any .service files
2542020-03-10 21:53:43.929 T:4092502032 DEBUG: Selected UDev as storage provider
2552020-03-10 21:53:43.929 T:4092502032 INFO: RetroPlayer[PROCESS]: Registering process control for Amlogic
2562020-03-10 21:53:43.929 T:4092502032 INFO: RetroPlayer[RENDER]: Registering renderer factory for OpenGLES
2572020-03-10 21:53:43.929 T:4092502032 ERROR: GetString: error reading /storage/.kodi/userdata/disp_cap
2582020-03-10 21:53:43.930 T:4092502032 ERROR: GetString: error reading /sys/class/amhdmitx/amhdmitx0/vesa_cap
2592020-03-10 21:53:43.930 T:4092502032 ERROR: GetString: error reading /sys/class/amhdmitx/amhdmitx0/custom_mode
2602020-03-10 21:53:43.930 T:4092502032 ERROR: GetString: error reading /storage/.kodi/userdata/disp_add
2612020-03-10 21:53:43.930 T:4092502032 NOTICE: Found resolution 720 x 480 with 720 x 480 @ 60.000000 Hz
2622020-03-10 21:53:43.930 T:4092502032 NOTICE: Found resolution 720 x 480 with 720 x 480 @ 59.940060 Hz
2632020-03-10 21:53:43.930 T:4092502032 NOTICE: Found resolution 1280 x 720 with 1280 x 720 @ 60.000000 Hz
2642020-03-10 21:53:43.930 T:4092502032 NOTICE: Found resolution 1280 x 720 with 1280 x 720 @ 59.940060 Hz
2652020-03-10 21:53:43.930 T:4092502032 NOTICE: Found resolution 1920 x 1080 with 1920 x 1080i @ 60.000000 Hz
2662020-03-10 21:53:43.930 T:4092502032 NOTICE: Found resolution 1920 x 1080 with 1920 x 1080i @ 59.940060 Hz
2672020-03-10 21:53:43.930 T:4092502032 NOTICE: Found resolution 1920 x 1080 with 1920 x 1080 @ 60.000000 Hz
2682020-03-10 21:53:43.930 T:4092502032 NOTICE: Found resolution 1920 x 1080 with 1920 x 1080 @ 59.940060 Hz
2692020-03-10 21:53:43.930 T:4092502032 NOTICE: Found resolution 1920 x 1080 with 1920 x 1080 @ 24.000000 Hz
2702020-03-10 21:53:43.930 T:4092502032 NOTICE: Found resolution 1920 x 1080 with 1920 x 1080 @ 23.976025 Hz
2712020-03-10 21:53:43.930 T:4092502032 NOTICE: Found resolution 1920 x 1080 with 3840 x 2160 @ 30.000000 Hz
2722020-03-10 21:53:43.930 T:4092502032 NOTICE: Found resolution 1920 x 1080 with 3840 x 2160 @ 29.970030 Hz
2732020-03-10 21:53:43.930 T:4092502032 NOTICE: Found resolution 1920 x 1080 with 3840 x 2160 @ 25.000000 Hz
2742020-03-10 21:53:43.931 T:4092502032 NOTICE: Found resolution 1920 x 1080 with 3840 x 2160 @ 24.000000 Hz
2752020-03-10 21:53:43.931 T:4092502032 NOTICE: Found resolution 1920 x 1080 with 3840 x 2160 @ 23.976025 Hz
2762020-03-10 21:53:43.931 T:4092502032 NOTICE: Found resolution 1920 x 1080 with 4096 x 2160 @ 24.000000 Hz
2772020-03-10 21:53:43.931 T:4092502032 NOTICE: Found resolution 1920 x 1080 with 4096 x 2160 @ 23.976025 Hz
2782020-03-10 21:53:43.931 T:4092502032 NOTICE: Found resolution 1920 x 1080 with 3840 x 2160 @ 60.000000 Hz
2792020-03-10 21:53:43.931 T:4092502032 NOTICE: Found resolution 1920 x 1080 with 3840 x 2160 @ 59.940060 Hz
2802020-03-10 21:53:43.931 T:4092502032 NOTICE: Found (1920x1080@24.000000) at 31, setting to RES_DESKTOP at 16
2812020-03-10 21:53:43.941 T:4092502032 NOTICE: EGL_VERSION = 1.4 Linux-r5p1-01rel0
2822020-03-10 21:53:43.941 T:4092502032 NOTICE: EGL_VENDOR = ARM
2832020-03-10 21:53:43.941 T:4092502032 NOTICE: EGL_EXTENSIONS = EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_gl_renderbuffer_image EGL_KHR_reusable_sync EGL_KHR_fence_sync EGL_KHR_lock_surface EGL_KHR_lock_surface2 EGL_EXT_create_context_robustness EGL_ANDROID_blob_cache EGL_KHR_create_context
2842020-03-10 21:53:43.941 T:4092502032 NOTICE: EGL_CLIENT_EXTENSIONS = NULL
2852020-03-10 21:53:43.941 T:4092502032 DEBUG: EGL Config Attributes:
2862020-03-10 21:53:43.941 T:4092502032 DEBUG: EGL_BUFFER_SIZE: 32
2872020-03-10 21:53:43.941 T:4092502032 DEBUG: EGL_ALPHA_SIZE: 8
2882020-03-10 21:53:43.941 T:4092502032 DEBUG: EGL_BLUE_SIZE: 8
2892020-03-10 21:53:43.941 T:4092502032 DEBUG: EGL_GREEN_SIZE: 8
2902020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_RED_SIZE: 8
2912020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_DEPTH_SIZE: 24
2922020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_STENCIL_SIZE: 8
2932020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_CONFIG_CAVEAT: 0x3038
2942020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_CONFIG_ID: 9
2952020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_LEVEL: 0
2962020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_MAX_PBUFFER_HEIGHT: 4096
2972020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_MAX_PBUFFER_PIXELS: 16777216
2982020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_MAX_PBUFFER_WIDTH: 4096
2992020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_NATIVE_RENDERABLE: 1
3002020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_NATIVE_VISUAL_ID: 0
3012020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_NATIVE_VISUAL_TYPE: 0
3022020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_SAMPLES: 0
3032020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_SAMPLE_BUFFERS: 0
3042020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_SURFACE_TYPE: 1031
3052020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_TRANSPARENT_TYPE: 0x3038
3062020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_TRANSPARENT_BLUE_VALUE: -1
3072020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_TRANSPARENT_GREEN_VALUE: -1
3082020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_TRANSPARENT_RED_VALUE: -1
3092020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_BIND_TO_TEXTURE_RGB: 1
3102020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_BIND_TO_TEXTURE_RGBA: 1
3112020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_MIN_SWAP_INTERVAL: 0
3122020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_MAX_SWAP_INTERVAL: 10
3132020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_LUMINANCE_SIZE: 0
3142020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_ALPHA_MASK_SIZE: 0
3152020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_COLOR_BUFFER_TYPE: 0x308e
3162020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_RENDERABLE_TYPE: 5
3172020-03-10 21:53:43.942 T:4092502032 DEBUG: EGL_CONFORMANT: 5
3182020-03-10 21:53:43.943 T:4092502032 NOTICE: Checking resolution 16
3192020-03-10 21:53:43.943 T:4092502032 DEBUG: AMLUtils::aml_handle_display_stereo_mode old mode 3doff new mode 3doff
3202020-03-10 21:53:43.943 T:4092502032 DEBUG: AMLUtils::aml_handle_display_stereo_mode - no change needed
3212020-03-10 21:53:43.943 T:4092502032 ERROR: GetString: error reading /sys/class/amhdmitx/amhdmitx0/custom_mode
3222020-03-10 21:53:43.956 T:4082103168 DEBUG: CLibInputHandler::DeviceAdded - keyboard type device added: gpio_keypad (event0)
3232020-03-10 21:53:43.956 T:4082103168 DEBUG: CLibInputKeyboard::GetRepeat - delay: 500ms repeat: -1ms for gpio_keypad (event0)
3242020-03-10 21:53:44.006 T:4082103168 DEBUG: CLibInputHandler::DeviceAdded - keyboard type device added: cec_input (event1)
3252020-03-10 21:53:44.006 T:4082103168 DEBUG: CLibInputKeyboard::GetRepeat - could not get key repeat for event1 (Function not implemented)
3262020-03-10 21:53:44.007 T:4082103168 DEBUG: CLibInputKeyboard::GetRepeat - delay: 400ms repeat: 80ms for cec_input (event1)
3272020-03-10 21:53:44.046 T:4082103168 DEBUG: CLibInputHandler::DeviceAdded - pointer type device added: MCE IR Keyboard/Mouse (meson-ir) (event4)
3282020-03-10 21:53:44.046 T:4082103168 DEBUG: CLibInputHandler::DeviceAdded - keyboard type device added: MCE IR Keyboard/Mouse (meson-ir) (event4)
3292020-03-10 21:53:44.046 T:4082103168 DEBUG: CLibInputKeyboard::GetRepeat - delay: 500ms repeat: 33ms for MCE IR Keyboard/Mouse (meson-ir) (event4)
3302020-03-10 21:53:44.265 T:4092502032 DEBUG: AMLUtils::aml_handle_display_stereo_mode old mode 3doff new mode 3doff
3312020-03-10 21:53:44.266 T:4092502032 DEBUG: AMLUtils::aml_handle_display_stereo_mode - no change needed
3322020-03-10 21:53:44.266 T:4092502032 NOTICE: GL_VENDOR = ARM
3332020-03-10 21:53:44.266 T:4092502032 NOTICE: GL_RENDERER = Mali-450 MP
3342020-03-10 21:53:44.266 T:4092502032 NOTICE: GL_VERSION = OpenGL ES 2.0
3352020-03-10 21:53:44.266 T:4092502032 NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 1.00
3362020-03-10 21:53:44.267 T:4092502032 NOTICE: GL_EXTENSIONS = GL_OES_texture_npot GL_OES_vertex_array_object GL_OES_compressed_ETC1_RGB8_texture GL_EXT_compressed_ETC1_RGB8_sub_texture GL_OES_standard_derivatives GL_OES_EGL_image GL_OES_depth24 GL_ARM_rgba8 GL_ARM_mali_shader_binary GL_OES_depth_texture GL_OES_packed_depth_stencil GL_EXT_texture_format_BGRA8888 GL_OES_vertex_half_float GL_EXT_blend_minmax GL_OES_EGL_image_external GL_OES_EGL_sync GL_OES_rgb8_rgba8 GL_EXT_multisampled_render_to_texture GL_EXT_discard_framebuffer GL_OES_get_program_binary GL_ARM_mali_program_binary GL_EXT_shader_texture_lod GL_EXT_robustness GL_OES_depth_texture_cube_map GL_KHR_debug GL_ARM_shader_framebuffer_fetch GL_ARM_shader_framebuffer_fetch_depth_stencil GL_OES_mapbuffer
3372020-03-10 21:53:44.478 T:4092502032 DEBUG: AMLUtils::aml_handle_display_stereo_mode old mode 3doff new mode 3doff
3382020-03-10 21:53:44.478 T:4092502032 DEBUG: AMLUtils::aml_handle_display_stereo_mode - no change needed
3392020-03-10 21:53:44.478 T:4092502032 ERROR: GetString: error reading /sys/class/amhdmitx/amhdmitx0/custom_mode
3402020-03-10 21:53:44.806 T:4092502032 DEBUG: AMLUtils::aml_handle_display_stereo_mode old mode 3doff new mode 3doff
3412020-03-10 21:53:44.807 T:4092502032 DEBUG: AMLUtils::aml_handle_display_stereo_mode - no change needed
3422020-03-10 21:53:44.809 T:4092502032 INFO: GLES: Maximum texture width: 4096
3432020-03-10 21:53:44.810 T:4092502032 DEBUG: guilib: Fill viewport on change for solving rendering passes
3442020-03-10 21:53:44.811 T:4092502032 INFO: load keymapping
3452020-03-10 21:53:44.858 T:4092502032 INFO: Loading special://xbmc/system/keymaps/appcommand.xml
3462020-03-10 21:53:44.865 T:4092502032 INFO: Loading special://xbmc/system/keymaps/customcontroller.AppleRemote.xml
3472020-03-10 21:53:44.898 T:4092502032 INFO: Loading special://xbmc/system/keymaps/customcontroller.Harmony.xml
3482020-03-10 21:53:44.903 T:4092502032 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
3492020-03-10 21:53:44.906 T:4092502032 INFO: Loading special://xbmc/system/keymaps/joystick.xml
3502020-03-10 21:53:44.910 T:4092502032 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
3512020-03-10 21:53:44.921 T:4092502032 INFO: Loading special://xbmc/system/keymaps/mouse.xml
3522020-03-10 21:53:44.922 T:4092502032 INFO: Loading special://xbmc/system/keymaps/remote.xml
3532020-03-10 21:53:44.928 T:4092502032 INFO: Loading special://xbmc/system/keymaps/touchscreen.xml
3542020-03-10 21:53:44.932 T:4092502032 INFO: GUI format 1920x1080, Display 4096x2160 @ 24.00 - Full Screen
3552020-03-10 21:53:44.932 T:4092502032 INFO: CLangInfo: loading resource.language.en_gb language information...
3562020-03-10 21:53:44.934 T:4092502032 DEBUG: trying to set locale to en_US.UTF-8
3572020-03-10 21:53:44.935 T:4092502032 INFO: global locale set to C
3582020-03-10 21:53:44.935 T:4092502032 INFO: CLangInfo: loading resource.language.en_gb language strings...
3592020-03-10 21:53:44.970 T:4092502032 DEBUG: LocalizeStrings: loaded 4032 strings from file resource://resource.language.en_gb/strings.po
3602020-03-10 21:53:44.982 T:4092502032 DEBUG: LocalizeStrings: loaded 18 strings from file /usr/share/kodi/addons/game.controller.default/resources/language/resource.language.en_gb/strings.po
3612020-03-10 21:53:44.983 T:4092502032 DEBUG: LocalizeStrings: loaded 17 strings from file /storage/.kodi/addons/metadata.album.universal/resources/language/resource.language.en_gb/strings.po
3622020-03-10 21:53:44.983 T:4092502032 DEBUG: LocalizeStrings: loaded 32 strings from file /storage/.kodi/addons/metadata.artists.universal/resources/language/resource.language.en_gb/strings.po
3632020-03-10 21:53:44.987 T:4092502032 DEBUG: LocalizeStrings: loaded 8 strings from file /storage/.kodi/addons/metadata.themoviedb.org/resources/language/resource.language.en_gb/strings.po
3642020-03-10 21:53:44.989 T:4092502032 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/screensaver.xbmc.builtin.dim/resources/language/resource.language.en_gb/strings.po
3652020-03-10 21:53:44.992 T:4092502032 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.kodi.builtin.aac/resources/language/English/strings.po
3662020-03-10 21:53:44.992 T:4092502032 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.kodi.builtin.wma/resources/language/English/strings.po
3672020-03-10 21:53:44.993 T:4092502032 DEBUG: LocalizeStrings: loaded 13 strings from file /usr/share/kodi/addons/game.controller.snes/resources/language/resource.language.en_gb/strings.po
3682020-03-10 21:53:44.994 T:4092502032 DEBUG: LocalizeStrings: loaded 10 strings from file /storage/.kodi/addons/metadata.tvshows.themoviedb.org/resources/language/resource.language.en_gb/strings.po
3692020-03-10 21:53:44.994 T:4092502032 DEBUG: LocalizeStrings: loaded 9 strings from file /usr/share/kodi/addons/peripheral.joystick/resources/language/resource.language.en_gb/strings.po
3702020-03-10 21:53:44.996 T:4092502032 DEBUG: LocalizeStrings: loaded 18 strings from file /usr/share/kodi/addons/script.program.driverselect/resources/language/resource.language.en_gb/strings.po
3712020-03-10 21:53:44.998 T:4092502032 DEBUG: LocalizeStrings: loaded 291 strings from file /usr/share/kodi/addons/service.coreelec.settings/resources/language/resource.language.en_gb/strings.po
3722020-03-10 21:53:44.998 T:4092502032 DEBUG: LocalizeStrings: loaded 10 strings from file /storage/.kodi/addons/metadata.tvdb.com/resources/language/resource.language.en_gb/strings.po
3732020-03-10 21:53:44.999 T:3987919744 DEBUG: Thread JobWorker start, auto delete: true
3742020-03-10 21:53:44.999 T:3987919744 DEBUG: Initialize, updating databases...
3752020-03-10 21:53:45.002 T:3987919744 NOTICE: Running database version Addons27
3762020-03-10 21:53:45.003 T:3987919744 NOTICE: Running database version ViewModes6
3772020-03-10 21:53:45.007 T:3987919744 NOTICE: Running database version Textures13
3782020-03-10 21:53:45.018 T:3987919744 NOTICE: Running database version MyMusic72
3792020-03-10 21:53:45.037 T:3987919744 NOTICE: Running database version MyVideos116
3802020-03-10 21:53:45.042 T:3987919744 NOTICE: Running database version TV32
3812020-03-10 21:53:45.043 T:3987919744 NOTICE: Running database version Epg12
3822020-03-10 21:53:45.044 T:3987919744 DEBUG: Initialize, updating databases... DONE
3832020-03-10 21:53:45.044 T:4092502032 NOTICE: start dvd mediatype detection
3842020-03-10 21:53:45.044 T:4073710464 DEBUG: Thread DetectDVDMedia start, auto delete: false
3852020-03-10 21:53:45.044 T:4073710464 DEBUG: Compiled with libcdio Version 0.94
3862020-03-10 21:53:45.044 T:4092502032 INFO: DPMS: not supported on this platform
3872020-03-10 21:53:45.045 T:4073710464 DEBUG: Thread DetectDVDMedia 4073710464 terminating
3882020-03-10 21:53:45.092 T:4092502032 INFO: Unloading old skin ...
3892020-03-10 21:53:45.094 T:4092502032 NOTICE: load skin from: /usr/share/kodi/addons/skin.estuary (version: 2.0.27)
3902020-03-10 21:53:45.094 T:4092502032 INFO: load fonts for skin...
3912020-03-10 21:53:45.097 T:4092502032 INFO: Loading skin includes from /usr/share/kodi/addons/skin.estuary/xml/Includes.xml
3922020-03-10 21:53:45.210 T:4092502032 INFO: Loading fonts from /usr/share/kodi/addons/skin.estuary/xml/Font.xml
3932020-03-10 21:53:45.251 T:4092502032 DEBUG: LocalizeStrings: loaded 143 strings from file /usr/share/kodi/addons/skin.estuary/language/resource.language.en_gb/strings.po
3942020-03-10 21:53:45.251 T:4092502032 INFO: load new skin...
3952020-03-10 21:53:45.251 T:4092502032 INFO: Loading custom window XMLs from skin path /usr/share/kodi/addons/skin.estuary/xml
3962020-03-10 21:53:45.265 T:4092502032 DEBUG: Load Skin XML: 13.96ms
3972020-03-10 21:53:45.265 T:4092502032 INFO: initialize new skin...
3982020-03-10 21:53:45.266 T:4092502032 DEBUG: guilib: Fill viewport on change for solving rendering passes
3992020-03-10 21:53:45.266 T:4092502032 INFO: Loading skin file: VideoOSD.xml, load type: LOAD_ON_GUI_INIT
4002020-03-10 21:53:45.285 T:4092502032 DEBUG: OpenBundle - Opened bundle /usr/share/kodi/addons/skin.estuary/media/Textures.xbt
4012020-03-10 21:53:45.291 T:4092502032 INFO: Loading skin file: DialogPVRChannelGuide.xml, load type: LOAD_ON_GUI_INIT
4022020-03-10 21:53:45.298 T:4092502032 INFO: Loading skin file: Custom_1109_TopBarOverlay.xml, load type: LOAD_ON_GUI_INIT
4032020-03-10 21:53:45.304 T:4092502032 INFO: Loading skin file: DialogPVRChannelsOSD.xml, load type: LOAD_ON_GUI_INIT
4042020-03-10 21:53:45.312 T:4092502032 INFO: Loading skin file: DialogNotification.xml, load type: LOAD_ON_GUI_INIT
4052020-03-10 21:53:45.314 T:4092502032 INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
4062020-03-10 21:53:45.341 T:4092502032 INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
4072020-03-10 21:53:45.344 T:4092502032 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
4082020-03-10 21:53:45.347 T:4092502032 INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
4092020-03-10 21:53:45.349 T:4092502032 INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
4102020-03-10 21:53:45.350 T:4092502032 INFO: Loading skin file: DialogSettings.xml, load type: LOAD_ON_GUI_INIT
4112020-03-10 21:53:45.358 T:4092502032 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'icons/logo.png'
4122020-03-10 21:53:45.360 T:4092502032 DEBUG: Previous line repeats 1 times.
4132020-03-10 21:53:45.360 T:4092502032 INFO: Loading skin file: DialogSettings.xml, load type: LOAD_ON_GUI_INIT
4142020-03-10 21:53:45.367 T:4092502032 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'icons/logo.png'
4152020-03-10 21:53:45.368 T:4092502032 DEBUG: Previous line repeats 1 times.
4162020-03-10 21:53:45.368 T:4092502032 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
4172020-03-10 21:53:45.370 T:4092502032 INFO: Loading skin file: DialogSubtitles.xml, load type: LOAD_ON_GUI_INIT
4182020-03-10 21:53:45.381 T:4092502032 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'icons/logo.png'
4192020-03-10 21:53:45.383 T:4092502032 DEBUG: Previous line repeats 1 times.
4202020-03-10 21:53:45.383 T:4092502032 INFO: Loading resource://resource.uisounds.kodi/sounds.xml
4212020-03-10 21:53:45.438 T:4092502032 INFO: skin loaded...
4222020-03-10 21:53:45.438 T:4092502032 DEBUG: Activating window ID: 12997
4232020-03-10 21:53:45.438 T:4092502032 DEBUG: ------ Window Init () ------
4242020-03-10 21:53:45.438 T:4092502032 DEBUG: Activating window ID: 12999
4252020-03-10 21:53:45.438 T:4092502032 DEBUG: ------ Window Init (Startup.xml) ------
4262020-03-10 21:53:45.438 T:4092502032 INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
4272020-03-10 21:53:45.439 T:4092502032 DEBUG: Activating window ID: 10000
4282020-03-10 21:53:45.439 T:4092502032 DEBUG: ------ Window Deinit (Startup.xml) ------
4292020-03-10 21:53:45.439 T:4092502032 DEBUG: ------ Window Init (Home.xml) ------
4302020-03-10 21:53:45.439 T:4092502032 INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
4312020-03-10 21:53:45.749 T:4092502032 DEBUG: CDirectoryProvider[library://video/movies/]: refreshing..
4322020-03-10 21:53:45.749 T:4092502032 DEBUG: CDirectoryProvider[special://skin/playlists/inprogress_movies.xsp]: refreshing..
4332020-03-10 21:53:45.750 T:4092502032 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_movies.xsp]: refreshing..
4342020-03-10 21:53:45.750 T:4092502032 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_movies.xsp]: refreshing..
4352020-03-10 21:53:45.750 T:3979527040 DEBUG: Thread JobWorker start, auto delete: true
4362020-03-10 21:53:45.750 T:4092502032 DEBUG: CDirectoryProvider[special://skin/playlists/random_movies.xsp]: refreshing..
4372020-03-10 21:53:45.750 T:4092502032 DEBUG: CDirectoryProvider[videodb://movies/genres/]: refreshing..
4382020-03-10 21:53:45.750 T:3971134336 DEBUG: Thread JobWorker start, auto delete: true
4392020-03-10 21:53:45.750 T:4092502032 DEBUG: CDirectoryProvider[videodb://movies/sets/]: refreshing..
4402020-03-10 21:53:45.750 T:4092502032 DEBUG: CDirectoryProvider[library://video/tvshows/]: refreshing..
4412020-03-10 21:53:45.750 T:4092502032 DEBUG: CDirectoryProvider[videodb://inprogresstvshows]: refreshing..
4422020-03-10 21:53:45.750 T:4092502032 DEBUG: CDirectoryProvider[special://skin/playlists/recent_unwatched_episodes.xsp]: refreshing..
4432020-03-10 21:53:45.751 T:4092502032 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_tvshows.xsp]: refreshing..
4442020-03-10 21:53:45.751 T:4092502032 DEBUG: CDirectoryProvider[videodb://tvshows/genres/]: refreshing..
4452020-03-10 21:53:45.751 T:4092502032 DEBUG: CDirectoryProvider[videodb://tvshows/studios/]: refreshing..
4462020-03-10 21:53:45.751 T:4092502032 DEBUG: CDirectoryProvider[library://music/]: refreshing..
4472020-03-10 21:53:45.751 T:4092502032 DEBUG: CDirectoryProvider[musicdb://recentlyplayedalbums]: refreshing..
4482020-03-10 21:53:45.751 T:4092502032 DEBUG: CDirectoryProvider[musicdb://recentlyaddedalbums/]: refreshing..
4492020-03-10 21:53:45.752 T:4092502032 DEBUG: CDirectoryProvider[special://skin/playlists/random_albums.xsp]: refreshing..
4502020-03-10 21:53:45.752 T:4092502032 DEBUG: CDirectoryProvider[special://skin/playlists/random_artists.xsp]: refreshing..
4512020-03-10 21:53:45.752 T:4092502032 DEBUG: CDirectoryProvider[special://skin/playlists/unplayed_albums.xsp]: refreshing..
4522020-03-10 21:53:45.752 T:4092502032 DEBUG: CDirectoryProvider[special://skin/playlists/mostplayed_albums.xsp]: refreshing..
4532020-03-10 21:53:45.752 T:4092502032 DEBUG: CDirectoryProvider[addons://]: refreshing..
4542020-03-10 21:53:45.752 T:4092502032 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing..
4552020-03-10 21:53:45.752 T:4092502032 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing..
4562020-03-10 21:53:45.752 T:4092502032 DEBUG: CDirectoryProvider[addons://sources/game/]: refreshing..
4572020-03-10 21:53:45.752 T:4092502032 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing..
4582020-03-10 21:53:45.753 T:4092502032 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing..
4592020-03-10 21:53:45.754 T:4092502032 DEBUG: CDirectoryProvider[library://video/]: refreshing..
4602020-03-10 21:53:45.754 T:4092502032 DEBUG: CDirectoryProvider[sources://video/]: refreshing..
4612020-03-10 21:53:45.754 T:4092502032 DEBUG: CDirectoryProvider[special://videoplaylists/]: refreshing..
4622020-03-10 21:53:45.754 T:4092502032 DEBUG: CDirectoryProvider[favourites://]: refreshing..
4632020-03-10 21:53:45.755 T:4092502032 DEBUG: CDirectoryProvider[sources://pictures/]: refreshing..
4642020-03-10 21:53:45.755 T:4092502032 DEBUG: CDirectoryProvider[addons://sources/game/]: refreshing..
4652020-03-10 21:53:45.817 T:4092502032 DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
4662020-03-10 21:53:45.817 T:4092502032 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
4672020-03-10 21:53:45.818 T:4092502032 INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
4682020-03-10 21:53:45.818 T:4092502032 INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
4692020-03-10 21:53:45.884 T:4092502032 INFO: JSONRPC v10.3.0: Successfully initialized
4702020-03-10 21:53:45.886 T:4092502032 DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
4712020-03-10 21:53:45.886 T:4092502032 DEBUG: LoadMappings - loaded node "CEC Adapter"
4722020-03-10 21:53:45.886 T:4092502032 DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
4732020-03-10 21:53:45.887 T:4092502032 DEBUG: LoadMappings - loaded node "iMON HID device"
4742020-03-10 21:53:45.887 T:4092502032 DEBUG: LoadMappings - loaded node "WETEK Play remote"
4752020-03-10 21:53:45.887 T:4092502032 DEBUG: LoadMappings - loaded node ""
4762020-03-10 21:53:45.888 T:4092502032 DEBUG: Add-on bus: Registering add-on peripheral.joystick
4772020-03-10 21:53:45.889 T:4092502032 DEBUG: PERIPHERAL - CreateAddon - creating peripheral add-on instance 'Joystick Support'
4782020-03-10 21:53:45.889 T:4092502032 DEBUG: ADDON: Dll Initializing - Joystick Support
4792020-03-10 21:53:45.889 T:4092502032 DEBUG: SECTION:LoadDLL(/usr/lib/kodi/addons/peripheral.joystick/peripheral.joystick.so.1.4.9)
4802020-03-10 21:53:45.889 T:4092502032 DEBUG: Loading: /usr/lib/kodi/addons/peripheral.joystick/peripheral.joystick.so.1.4.9
4812020-03-10 21:53:45.900 T:4092502032 DEBUG: AddOnLog: Joystick Support: Loaded 14 joystick families with 97 total joysticks
4822020-03-10 21:53:45.900 T:4092502032 DEBUG: Calling TransferSettings for: Joystick Support
4832020-03-10 21:53:45.901 T:4092502032 DEBUG: CAddonSettings[peripheral.joystick]: loading setting definitions
4842020-03-10 21:53:45.901 T:4092502032 INFO: AddOnLog: Joystick Support: Enabling joystick interface "linux"
4852020-03-10 21:53:45.902 T:3839697792 DEBUG: Thread PeripBusUSBUdev start, auto delete: false
4862020-03-10 21:53:45.903 T:3839697792 DEBUG: Process - initialised udev monitor
4872020-03-10 21:53:45.904 T:3831305088 DEBUG: Thread PeripBusCEC start, auto delete: false
4882020-03-10 21:53:45.906 T:3822912384 DEBUG: Thread PeripBusAddon start, auto delete: false
4892020-03-10 21:53:45.908 T:4092502032 DEBUG: Initialise - initialised peripheral on 'keyboard' with 1 features and 0 sub devices
4902020-03-10 21:53:45.908 T:4092502032 NOTICE: Register - new keyboard device registered on application->keyboard: Keyboard (0000:0000)
4912020-03-10 21:53:45.908 T:4092502032 DEBUG: Initialise - initialised peripheral on 'mouse' with 1 features and 0 sub devices
4922020-03-10 21:53:45.908 T:4092502032 NOTICE: Register - new mouse device registered on application->mouse: Mouse (0000:0000)
4932020-03-10 21:53:45.913 T:4092502032 DEBUG: ContextMenuManager: addon menus reloaded.
4942020-03-10 21:53:45.913 T:4092502032 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
4952020-03-10 21:53:45.914 T:4092502032 DEBUG: CPlayerCoreConfig::<ctor>: created player VideoPlayer
4962020-03-10 21:53:45.914 T:4092502032 DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer
4972020-03-10 21:53:45.914 T:4092502032 DEBUG: CPlayerCoreConfig::<ctor>: created player RetroPlayer
4982020-03-10 21:53:45.914 T:4092502032 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
4992020-03-10 21:53:45.914 T:4092502032 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mms/udp
5002020-03-10 21:53:45.914 T:4092502032 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
5012020-03-10 21:53:45.914 T:4092502032 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
5022020-03-10 21:53:45.914 T:4092502032 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
5032020-03-10 21:53:45.914 T:4092502032 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
5042020-03-10 21:53:45.914 T:4092502032 DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
5052020-03-10 21:53:45.914 T:4092502032 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
5062020-03-10 21:53:45.914 T:4092502032 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
5072020-03-10 21:53:45.914 T:4092502032 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
5082020-03-10 21:53:45.914 T:4092502032 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
5092020-03-10 21:53:45.914 T:4092502032 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
5102020-03-10 21:53:45.914 T:4092502032 DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
5112020-03-10 21:53:45.914 T:4092502032 NOTICE: Loaded playercorefactory configuration
5122020-03-10 21:53:45.915 T:4092502032 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
5132020-03-10 21:53:45.915 T:4092502032 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
5142020-03-10 21:53:45.915 T:4092502032 INFO: removing tempfiles
5152020-03-10 21:53:45.915 T:3814519680 DEBUG: Thread PeripEventScanner start, auto delete: false
5162020-03-10 21:53:45.922 T:4092502032 DEBUG: CServiceAddonManager: starting service.coreelec.settings
5172020-03-10 21:53:45.923 T:3806126976 DEBUG: Thread LanguageInvoker start, auto delete: false
5182020-03-10 21:53:45.924 T:4092502032 NOTICE: initialize done
5192020-03-10 21:53:45.924 T:4092502032 NOTICE: Running the application...
5202020-03-10 21:53:45.924 T:3806126976 INFO: initializing python engine.
5212020-03-10 21:53:45.926 T:4092502032 DEBUG: no profile autoexec.py (/storage/.kodi/userdata/autoexec.py) found, skipping
5222020-03-10 21:53:45.926 T:4092502032 DEBUG: NetworkMessage - Starting network services
5232020-03-10 21:53:45.931 T:4092502032 DEBUG: CZeroconfAvahi::clientCallback: client is up and running
5242020-03-10 21:53:45.932 T:4092502032 NOTICE: starting zeroconf publishing
5252020-03-10 21:53:45.937 T:4092502032 NOTICE: CWebServer[8080]: Started
5262020-03-10 21:53:45.938 T:4092502032 NOTICE: starting upnp client
5272020-03-10 21:53:45.946 T:4092502032 NOTICE: starting upnp server
5282020-03-10 21:53:45.957 T:4092502032 NOTICE: starting upnp renderer
5292020-03-10 21:53:46.068 T:3806126976 DEBUG: CPythonInvoker(0, /usr/share/kodi/addons/service.coreelec.settings/service.py): start processing
5302020-03-10 21:53:46.153 T:3831305088 DEBUG: GetMappingForDevice - device (0000:0000) mapped to CEC Adapter (type = cec)
5312020-03-10 21:53:46.156 T:3831305088 DEBUG: InitialiseFeature - using libCEC v4.0.4
5322020-03-10 21:53:46.156 T:3831305088 DEBUG: Initialise - initialised peripheral on 'AOCEC' with 2 features and 0 sub devices
5332020-03-10 21:53:46.156 T:3831305088 NOTICE: Register - new cec device registered on cec->AOCEC: CEC Adapter (0000:0000)
5342020-03-10 21:53:46.160 T:3693077376 DEBUG: Thread CECAdapter start, auto delete: false
5352020-03-10 21:53:46.160 T:3693077376 DEBUG: OpenConnection - opening a connection to the CEC adapter: AOCEC
5362020-03-10 21:53:46.161 T:3701470080 DEBUG: CecLogMessage - Broadcast (F): osd name set to 'Broadcast'
5372020-03-10 21:53:46.163 T:3806126976 DEBUG: -->Python Interpreter Initialized<--
5382020-03-10 21:53:46.164 T:3806126976 DEBUG: CPythonInvoker(0, /usr/share/kodi/addons/service.coreelec.settings/service.py): the source file to load is "/usr/share/kodi/addons/service.coreelec.settings/service.py"
5392020-03-10 21:53:46.164 T:3701470080 DEBUG: CecLogMessage - connection opened
5402020-03-10 21:53:46.164 T:3806126976 DEBUG: CPythonInvoker(0, /usr/share/kodi/addons/service.coreelec.settings/service.py): setting the Python path to /usr/share/kodi/addons/service.coreelec.settings:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages/gtk-2.0
5412020-03-10 21:53:46.164 T:3806126976 DEBUG: CPythonInvoker(0, /usr/share/kodi/addons/service.coreelec.settings/service.py): entering source directory /usr/share/kodi/addons/service.coreelec.settings
5422020-03-10 21:53:46.165 T:3701470080 DEBUG: CecLogMessage - processor thread started
5432020-03-10 21:53:46.165 T:3806126976 DEBUG: CPythonInvoker(0, /usr/share/kodi/addons/service.coreelec.settings/service.py): instantiating addon using automatically obtained id of "service.coreelec.settings" dependent on version 2.1.0 of the xbmc.python api
5442020-03-10 21:53:46.165 T:3701470080 DEBUG: CecLogMessage - << Broadcast (F) -> TV (0): POLL
5452020-03-10 21:53:46.165 T:3701470080 DEBUG: CecLogMessage - << f0
5462020-03-10 21:53:46.236 T:3701470080 DEBUG: CecLogMessage - >> POLL sent
5472020-03-10 21:53:46.236 T:3701470080 DEBUG: CecLogMessage - TV (0): device status changed into 'present'
5482020-03-10 21:53:46.237 T:3701470080 DEBUG: CecLogMessage - << requesting vendor ID of 'TV' (0)
5492020-03-10 21:53:46.242 T:3701470080 DEBUG: CecLogMessage - << f0:8c
5502020-03-10 21:53:46.526 T:3806126976 DEBUG: ## CoreELEC Addon ## 9.0.0
5512020-03-10 21:53:46.565 T:3979527040 DEBUG: GetRecentlyPlayedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM albumview WHERE albumview.lastplayed IS NOT NULL AND albumview.strReleaseType = 'album' ORDER BY albumview.lastplayed DESC LIMIT 25) as playedalbums JOIN albumview ON albumview.idAlbum = playedalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY albumview.lastplayed DESC, albumartistview.iorder
5522020-03-10 21:53:46.571 T:3971134336 DEBUG: GetRecentlyAddedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM album WHERE strAlbum != '' ORDER BY idAlbum DESC LIMIT 25) AS recentalbums JOIN albumview ON albumview.idAlbum = recentalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY albumview.idAlbum desc, albumartistview.iOrder
5532020-03-10 21:53:46.628 T:3979527040 DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview WHERE albumview.strReleaseType = 'album' ORDER BY RANDOM() LIMIT 15
5542020-03-10 21:53:46.635 T:3971134336 DEBUG: GetArtistsByWhere query: SELECT artistview.* FROM artistview WHERE (EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '') ORDER BY RANDOM() LIMIT 15
5552020-03-10 21:53:46.642 T:3979527040 DEBUG: GetAlbumsByWhere: Time to fill list with albums 18ms query took 10ms
5562020-03-10 21:53:46.663 T:3971134336 DEBUG: GetArtistsByWhere: Time to fill list with artists 34ms query took 16ms
5572020-03-10 21:53:46.700 T:3971134336 DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview WHERE (((CAST(albumview.iTimesPlayed as DECIMAL(5,1)) > 0))) AND (albumview.strReleaseType = 'album')
5582020-03-10 21:53:46.719 T:3979527040 DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview WHERE (((CAST(albumview.iTimesPlayed as DECIMAL(5,1)) = 0))) AND (albumview.strReleaseType = 'album') ORDER BY RANDOM() LIMIT 15
5592020-03-10 21:53:46.767 T:3971134336 DEBUG: CAddonMgr::GetAvailableUpdates took 39 ms
5602020-03-10 21:53:46.771 T:3979527040 DEBUG: GetAlbumsByWhere: Time to fill list with albums 77ms query took 48ms
5612020-03-10 21:53:46.832 T:3934094208 DEBUG: Thread EventServer start, auto delete: false
5622020-03-10 21:53:46.832 T:3934094208 NOTICE: ES: Starting UDP Event server on port 9777
5632020-03-10 21:53:46.832 T:3934094208 NOTICE: UDP: Listening on port 9777 (ipv6 : false)
5642020-03-10 21:53:46.834 T:4092502032 DEBUG: JSONRPC Server: Failed to bind IPv4 serversocket
5652020-03-10 21:53:46.834 T:4092502032 INFO: JSONRPC Server: Successfully initialized
5662020-03-10 21:53:46.834 T:3925701504 DEBUG: Thread TCPServer start, auto delete: false
5672020-03-10 21:53:46.834 T:4092502032 DEBUG: LogindUPowerSyscall - Received unknown signal NameAcquired
5682020-03-10 21:53:46.874 T:3971134336 DEBUG: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
5692020-03-10 21:53:46.874 T:3971134336 DEBUG: Getting Directory (special://profile/playlists/video)
5702020-03-10 21:53:46.875 T:3971134336 DEBUG: Getting Directory (special://profile/playlists/mixed)
5712020-03-10 21:53:46.876 T:3971134336 DEBUG: CMultiPathDirectory::MergeItems, items = 0
5722020-03-10 21:53:46.924 T:3806126976 INFO: ## CoreELEC Addon ## system::set_hostname ## CoreELEC
5732020-03-10 21:53:46.925 T:3806126976 INFO: ## CoreELEC Addon ## system::set_keyboard_layout ## us
5742020-03-10 21:53:46.925 T:3806126976 INFO: ## CoreELEC Addon ## system::set_keyboard_layout ## loadkmap < `ls -1 /usr/lib/keymaps/*/us.bmap`
5752020-03-10 21:53:47.120 T:3979527040 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webserver type: _http._tcp name:Kodi (CoreELEC) port:8080
5762020-03-10 21:53:47.120 T:3979527040 DEBUG: CZeroconfAvahi::addService() named: Kodi (CoreELEC) type: _http._tcp port:8080
5772020-03-10 21:53:47.135 T:3987919744 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-http type: _xbmc-jsonrpc-h._tcp name:Kodi (CoreELEC) port:8080
5782020-03-10 21:53:47.137 T:3979527040 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:Kodi (CoreELEC) port:9777
5792020-03-10 21:53:47.137 T:3987919744 DEBUG: CZeroconfAvahi::addService() named: Kodi (CoreELEC) type: _xbmc-jsonrpc-h._tcp port:8080
5802020-03-10 21:53:47.140 T:3987919744 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:Kodi (CoreELEC) port:9090
5812020-03-10 21:53:47.141 T:3987919744 DEBUG: CZeroconfAvahi::addService() named: Kodi (CoreELEC) type: _xbmc-jsonrpc._tcp port:9090
5822020-03-10 21:53:47.144 T:3979527040 DEBUG: CZeroconfAvahi::addService() named: Kodi (CoreELEC) type: _xbmc-events._udp port:9777
5832020-03-10 21:53:47.247 T:3879011200 DEBUG: Thread JobWorker start, auto delete: true
5842020-03-10 21:53:47.257 T:3979527040 DEBUG: ffmpeg[ED32C380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
5852020-03-10 21:53:47.257 T:3987919744 DEBUG: ffmpeg[EDB2D380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
5862020-03-10 21:53:47.263 T:3879011200 DEBUG: ffmpeg[E7350380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
5872020-03-10 21:53:47.272 T:3987919744 DEBUG: ffmpeg[EDB2D380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
5882020-03-10 21:53:47.275 T:3979527040 DEBUG: ffmpeg[ED32C380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
5892020-03-10 21:53:47.279 T:3879011200 DEBUG: ffmpeg[E7350380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
5902020-03-10 21:53:47.279 T:4092502032 DEBUG: ------ Window Init () ------
5912020-03-10 21:53:47.285 T:4092502032 DEBUG: ------ Window Init (Pointer.xml) ------
5922020-03-10 21:53:47.286 T:3971134336 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
5932020-03-10 21:53:47.306 T:3987919744 DEBUG: ffmpeg[EDB2D380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
5942020-03-10 21:53:47.309 T:3979527040 DEBUG: ffmpeg[ED32C380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
5952020-03-10 21:53:47.310 T:3971134336 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
5962020-03-10 21:53:47.326 T:3879011200 DEBUG: ffmpeg[E7350380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
5972020-03-10 21:53:47.337 T:3971134336 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
5982020-03-10 21:53:47.342 T:3979527040 DEBUG: ffmpeg[ED32C380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
5992020-03-10 21:53:47.345 T:3987919744 DEBUG: ffmpeg[EDB2D380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
6002020-03-10 21:53:47.358 T:3701470080 DEBUG: CecLogMessage - expected response not received (87: device vendor id)
6012020-03-10 21:53:47.359 T:3701470080 DEBUG: CecLogMessage - << f0:8c
6022020-03-10 21:53:47.361 T:3971134336 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
6032020-03-10 21:53:47.374 T:3879011200 DEBUG: ffmpeg[E7350380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
6042020-03-10 21:53:47.388 T:3979527040 DEBUG: ffmpeg[ED32C380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
6052020-03-10 21:53:47.415 T:3987919744 DEBUG: ffmpeg[EDB2D380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
6062020-03-10 21:53:47.425 T:3879011200 DEBUG: ffmpeg[E7350380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
6072020-03-10 21:53:47.428 T:4092502032 INFO: GLES: Enabling VSYNC
6082020-03-10 21:53:47.431 T:3979527040 DEBUG: ffmpeg[ED32C380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
6092020-03-10 21:53:47.443 T:3987919744 DEBUG: ffmpeg[EDB2D380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
6102020-03-10 21:53:47.465 T:3879011200 DEBUG: ffmpeg[E7350380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
6112020-03-10 21:53:47.467 T:3987919744 DEBUG: ffmpeg[EDB2D380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
6122020-03-10 21:53:47.473 T:3979527040 DEBUG: ffmpeg[ED32C380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
6132020-03-10 21:53:47.488 T:3879011200 DEBUG: ffmpeg[E7350380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
6142020-03-10 21:53:47.489 T:3987919744 DEBUG: ffmpeg[EDB2D380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
6152020-03-10 21:53:47.504 T:3701470080 DEBUG: CecLogMessage - >> 01
6162020-03-10 21:53:47.504 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
6172020-03-10 21:53:47.515 T:3879011200 DEBUG: ffmpeg[E7350380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
6182020-03-10 21:53:47.521 T:3979527040 DEBUG: ffmpeg[ED32C380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
6192020-03-10 21:53:47.533 T:3971134336 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
6202020-03-10 21:53:47.543 T:3971134336 DEBUG: GetArtistsByWhere query: SELECT COUNT(DISTINCT artistview.idArtist) FROM artistview WHERE (EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '')
6212020-03-10 21:53:47.597 T:3979527040 DEBUG: GetImageHash - unable to stat url smb://mrhowell/uploaddownload/xbmc_videodb_2020-03-04/movies/A_Midsummer_Night's_Sex_Comedy_1982-poster.jpg
6222020-03-10 21:53:47.601 T:3979527040 DEBUG: GetImageHash - unable to stat url smb://mrhowell/uploaddownload/xbmc_videodb_2020-03-04/movies/Hustlers_2019-poster.jpg
6232020-03-10 21:53:47.606 T:3979527040 DEBUG: GetImageHash - unable to stat url smb://mrhowell/uploaddownload/xbmc_videodb_2020-03-04/movies/When_Harry_Met_Sally..._1989-poster.jpg
6242020-03-10 21:53:47.610 T:3979527040 DEBUG: GetImageHash - unable to stat url smb://mrhowell/uploaddownload/xbmc_videodb_2020-03-04/movies/The_Da_Vinci_Code_2006-poster.jpg
6252020-03-10 21:53:47.613 T:3979527040 DEBUG: GetImageHash - unable to stat url smb://mrhowell/uploaddownload/xbmc_videodb_2020-03-04/movies/At_Eternity's_Gate_2018-poster.jpg
6262020-03-10 21:53:47.617 T:3979527040 DEBUG: GetImageHash - unable to stat url smb://mrhowell/uploaddownload/xbmc_videodb_2020-03-04/movies/Isle_of_Dogs_2018-poster.jpg
6272020-03-10 21:53:47.621 T:3979527040 DEBUG: GetImageHash - unable to stat url smb://mrhowell/uploaddownload/xbmc_videodb_2020-03-04/movies/Diana_2013-poster.jpg
6282020-03-10 21:53:47.624 T:3979527040 DEBUG: GetImageHash - unable to stat url smb://mrhowell/uploaddownload/xbmc_videodb_2020-03-04/movies/Bend_It_Like_Beckham_2002-poster.jpg
6292020-03-10 21:53:47.627 T:3979527040 DEBUG: GetImageHash - unable to stat url smb://mrhowell/uploaddownload/xbmc_videodb_2020-03-04/movies/Around_the_World_in_Eighty_Days_1956-poster.jpg
6302020-03-10 21:53:47.631 T:3979527040 DEBUG: GetImageHash - unable to stat url smb://mrhowell/uploaddownload/xbmc_videodb_2020-03-04/movies/Ocean's_Eleven_2001-poster.jpg
6312020-03-10 21:53:47.634 T:3979527040 DEBUG: GetImageHash - unable to stat url smb://mrhowell/uploaddownload/xbmc_videodb_2020-03-04/movies/Hangover_2009-thumb.jpg
6322020-03-10 21:53:47.638 T:3979527040 DEBUG: GetImageHash - unable to stat url smb://mrhowell/uploaddownload/xbmc_videodb_2020-03-04/movies/Deconstructing_Harry_1997-poster.jpg
6332020-03-10 21:53:47.730 T:3806126976 INFO: ## CoreELEC Addon ## updates::updateThread ## Started
6342020-03-10 21:53:47.731 T:3806126976 INFO: ## CoreELEC Addon ## updates::set_auto_update ## manual
6352020-03-10 21:53:47.827 T:3642721152 INFO: ## CoreELEC Addon ## _service_::run ## WAITING:
6362020-03-10 21:53:47.842 T:3651113856 INFO: ## CoreELEC Addon ## xdbus Monitor started. ##
6372020-03-10 21:53:47.965 T:3797734272 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
6382020-03-10 21:53:48.385 T:4092502032 DEBUG: Previous line repeats 3 times.
6392020-03-10 21:53:48.385 T:4092502032 DEBUG: ------ Window Deinit (Pointer.xml) ------
6402020-03-10 21:53:48.391 T:4092502032 DEBUG: ------ Window Init (DialogConfirm.xml) ------
6412020-03-10 21:53:48.391 T:4092502032 INFO: Loading skin file: DialogConfirm.xml, load type: KEEP_IN_MEMORY
6422020-03-10 21:53:48.406 T:4092502032 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'icons/logo.png'
6432020-03-10 21:53:48.456 T:3701470080 DEBUG: Previous line repeats 1 times.
6442020-03-10 21:53:48.457 T:3701470080 DEBUG: CecLogMessage - expected response not received (87: device vendor id)
6452020-03-10 21:53:48.457 T:3701470080 DEBUG: CecLogMessage - registering new CEC client - v4.0.0
6462020-03-10 21:53:48.457 T:3701470080 DEBUG: CecLogMessage - detecting logical address for type 'recording device'
6472020-03-10 21:53:48.457 T:3701470080 DEBUG: CecLogMessage - trying logical address 'Recorder 1'
6482020-03-10 21:53:48.457 T:3701470080 DEBUG: CecLogMessage - << Recorder 1 (1) -> Recorder 1 (1): POLL
6492020-03-10 21:53:48.457 T:3701470080 DEBUG: CecLogMessage - << 11
6502020-03-10 21:53:48.656 T:3701470080 WARNING: CecLogMessage - Write: write failed
6512020-03-10 21:53:48.657 T:3701470080 DEBUG: CecLogMessage - << 11
6522020-03-10 21:53:48.856 T:3701470080 WARNING: CecLogMessage - Write: write failed
6532020-03-10 21:53:48.857 T:3701470080 DEBUG: CecLogMessage - >> POLL not sent
6542020-03-10 21:53:48.857 T:3701470080 DEBUG: CecLogMessage - using logical address 'Recorder 1'
6552020-03-10 21:53:48.857 T:3701470080 DEBUG: CecLogMessage - Recorder 1 (1): device status changed into 'handled by libCEC'
6562020-03-10 21:53:48.857 T:3701470080 DEBUG: CecLogMessage - Recorder 1 (1): power status changed from 'unknown' to 'on'
6572020-03-10 21:53:48.857 T:3701470080 DEBUG: CecLogMessage - Recorder 1 (1): vendor = Pulse Eight (001582)
6582020-03-10 21:53:48.857 T:3701470080 DEBUG: CecLogMessage - Recorder 1 (1): CEC version 1.4
6592020-03-10 21:53:48.857 T:3701470080 DEBUG: CecLogMessage - AllocateLogicalAddresses - device '0', type 'recording device', LA '1'
6602020-03-10 21:53:48.859 T:3701470080 DEBUG: CecLogMessage - Recorder 1 (1): osd name set to 'CoreELEC'
6612020-03-10 21:53:48.859 T:3701470080 DEBUG: CecLogMessage - Recorder 1 (1): menu language set to 'eng'
6622020-03-10 21:53:48.859 T:3701470080 DEBUG: CecLogMessage - AutodetectPhysicalAddress - autodetected physical address '5000'
6632020-03-10 21:53:48.860 T:3701470080 DEBUG: CecLogMessage - Recorder 1 (1): physical address changed from ffff to 5000
6642020-03-10 21:53:48.860 T:3701470080 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): physical address 5000
6652020-03-10 21:53:48.860 T:3701470080 DEBUG: CecLogMessage - << 1f:84:50:00:01
6662020-03-10 21:53:49.029 T:3701470080 DEBUG: CecLogMessage - making Recorder 1 (1) the active source
6672020-03-10 21:53:49.029 T:3701470080 DEBUG: CecLogMessage - TV (0): power status changed from 'unknown' to 'in transition from standby to on'
6682020-03-10 21:53:49.029 T:3701470080 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
6692020-03-10 21:53:49.029 T:3701470080 DEBUG: CecLogMessage - scheduling active source message for 'Recorder 1'
6702020-03-10 21:53:49.029 T:3701470080 DEBUG: CecLogMessage - CEC client registered: libCEC version = 4.0.4, client version = 4.0.0, firmware version = 5, logical address(es) = Recorder 1 (1) , physical address: 5.0.0.0, compiled on Thu Mar 5 01:03:38 UTC 2020 by relkai@hammbwcor01 on Linux 4.15.0-72-generic (x86_64), features: P8_USB, DRM, P8_detect, AOCEC
6712020-03-10 21:53:49.029 T:3701470080 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): OSD name 'CoreELEC'
6722020-03-10 21:53:49.029 T:3701470080 DEBUG: CecLogMessage - << 10:47:43:6f:72:65:45:4c:45:43
6732020-03-10 21:53:49.063 T:3701470080 DEBUG: CecLogMessage - >> 01:46
6742020-03-10 21:53:49.063 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give osd name (46)
6752020-03-10 21:53:49.356 T:3701470080 DEBUG: CecLogMessage - << requesting power status of 'TV' (0)
6762020-03-10 21:53:49.357 T:3701470080 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): OSD name 'CoreELEC'
6772020-03-10 21:53:49.357 T:3701470080 DEBUG: CecLogMessage - << 10:8f
6782020-03-10 21:53:49.359 T:3701470080 DEBUG: CecLogMessage - << 10:47:43:6f:72:65:45:4c:45:43
6792020-03-10 21:53:49.678 T:3701470080 DEBUG: CecLogMessage - << requesting vendor ID of 'TV' (0)
6802020-03-10 21:53:49.678 T:3701470080 DEBUG: CecLogMessage - << 10:8c
6812020-03-10 21:53:50.799 T:3701470080 DEBUG: CecLogMessage - expected response not received (87: device vendor id)
6822020-03-10 21:53:50.799 T:3701470080 DEBUG: CecLogMessage - << 10:8c
6832020-03-10 21:53:50.918 T:3701470080 DEBUG: CecLogMessage - expected response not received (90: report power status)
6842020-03-10 21:53:50.918 T:3701470080 DEBUG: CecLogMessage - << 10:8f
6852020-03-10 21:53:51.888 T:3701470080 DEBUG: CecLogMessage - expected response not received (87: device vendor id)
6862020-03-10 21:53:51.888 T:3701470080 DEBUG: CecLogMessage - << powering on 'TV' (0)
6872020-03-10 21:53:51.888 T:3701470080 DEBUG: CecLogMessage - << 10:04
6882020-03-10 21:53:51.978 T:3701470080 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (5000)
6892020-03-10 21:53:51.978 T:3701470080 DEBUG: CecLogMessage - << 1f:82:50:00
6902020-03-10 21:53:52.007 T:3693077376 DEBUG: OpenConnection - connection to the CEC adapter opened
6912020-03-10 21:53:52.007 T:3693077376 DEBUG: SetConfigurationFromLibCEC - settings updated by libCEC
6922020-03-10 21:53:52.007 T:3701470080 DEBUG: CecLogMessage - expected response not received (90: report power status)
6932020-03-10 21:53:52.007 T:3634328448 DEBUG: Thread CECAdapterUpdate start, auto delete: false
6942020-03-10 21:53:52.007 T:3701470080 DEBUG: CecLogMessage - TV (0): power status changed from 'in transition from standby to on' to 'unknown'
6952020-03-10 21:53:52.158 T:3701470080 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
6962020-03-10 21:53:52.158 T:3701470080 DEBUG: CecLogMessage - << 10:8e:00
6972020-03-10 21:53:52.158 T:3701470080 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
6982020-03-10 21:53:52.158 T:3701470080 DEBUG: CecLogMessage - TV (0): power status changed from 'unknown' to 'in transition from standby to on'
6992020-03-10 21:53:52.297 T:3701470080 DEBUG: CecLogMessage - >> 01
7002020-03-10 21:53:52.297 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
7012020-03-10 21:53:52.297 T:3701470080 DEBUG: CecLogMessage - >> 01:90:00
7022020-03-10 21:53:52.297 T:3701470080 DEBUG: CecLogMessage - TV (0): power status changed from 'in transition from standby to on' to 'on'
7032020-03-10 21:53:52.298 T:3701470080 DEBUG: CecLogMessage - >> 01:90:00
7042020-03-10 21:53:52.298 T:3701470080 DEBUG: CecLogMessage - >> 01
7052020-03-10 21:53:52.298 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
7062020-03-10 21:53:52.298 T:3701470080 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
7072020-03-10 21:53:52.298 T:3701470080 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
7082020-03-10 21:53:52.298 T:3701470080 DEBUG: CecLogMessage - << requesting vendor ID of 'TV' (0)
7092020-03-10 21:53:52.298 T:3701470080 DEBUG: CecLogMessage - << 10:8c
7102020-03-10 21:53:52.298 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): report power status (90)
7112020-03-10 21:53:53.417 T:3701470080 DEBUG: Previous line repeats 1 times.
7122020-03-10 21:53:53.417 T:3701470080 DEBUG: CecLogMessage - expected response not received (87: device vendor id)
7132020-03-10 21:53:53.417 T:3701470080 DEBUG: CecLogMessage - << 10:8c
7142020-03-10 21:53:53.523 T:3701470080 DEBUG: CecLogMessage - >> 01
7152020-03-10 21:53:53.524 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
7162020-03-10 21:53:54.508 T:3701470080 DEBUG: CecLogMessage - expected response not received (87: device vendor id)
7172020-03-10 21:53:54.508 T:3701470080 DEBUG: CecLogMessage - << powering on 'TV' (0)
7182020-03-10 21:53:54.508 T:3701470080 DEBUG: CecLogMessage - << 10:04
7192020-03-10 21:53:54.598 T:3701470080 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (5000)
7202020-03-10 21:53:54.598 T:3701470080 DEBUG: CecLogMessage - << 1f:82:50:00
7212020-03-10 21:53:54.736 T:3701470080 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
7222020-03-10 21:53:54.738 T:3701470080 DEBUG: CecLogMessage - << 10:8e:00
7232020-03-10 21:53:54.846 T:3634328448 DEBUG: UpdateMenuLanguage - using TV menu language is disabled
7242020-03-10 21:53:54.847 T:3634328448 DEBUG: UpdateAudioSystemStatus - no CEC capable amplifier found
7252020-03-10 21:53:55.007 T:3701470080 DEBUG: CecLogMessage - >> 01
7262020-03-10 21:53:55.007 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
7272020-03-10 21:53:56.507 T:3701470080 DEBUG: CecLogMessage - >> 01
7282020-03-10 21:53:56.508 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
7292020-03-10 21:53:58.008 T:3701470080 DEBUG: CecLogMessage - >> 01
7302020-03-10 21:53:58.008 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
7312020-03-10 21:53:59.509 T:3701470080 DEBUG: CecLogMessage - >> 01
7322020-03-10 21:53:59.509 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
7332020-03-10 21:54:01.009 T:3701470080 DEBUG: CecLogMessage - >> 01
7342020-03-10 21:54:01.009 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
7352020-03-10 21:54:02.510 T:3701470080 DEBUG: CecLogMessage - >> 01
7362020-03-10 21:54:02.511 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
7372020-03-10 21:54:04.011 T:3701470080 DEBUG: CecLogMessage - >> 01
7382020-03-10 21:54:04.011 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
7392020-03-10 21:54:05.511 T:3701470080 DEBUG: CecLogMessage - >> 01
7402020-03-10 21:54:05.511 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
7412020-03-10 21:54:07.011 T:3701470080 DEBUG: CecLogMessage - >> 01
7422020-03-10 21:54:07.012 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
7432020-03-10 21:54:08.512 T:3701470080 DEBUG: CecLogMessage - >> 01
7442020-03-10 21:54:08.512 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
7452020-03-10 21:54:10.013 T:3701470080 DEBUG: CecLogMessage - >> 01
7462020-03-10 21:54:10.013 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
7472020-03-10 21:54:10.715 T:3701470080 DEBUG: CecLogMessage - >> 01:44:00
7482020-03-10 21:54:10.715 T:3701470080 DEBUG: CecLogMessage - SetCurrentButton select (0) D:0ms cur:0
7492020-03-10 21:54:10.715 T:3701470080 DEBUG: CecLogMessage - key pressed: select (0) current(ff) duration(0)
7502020-03-10 21:54:10.715 T:3701470080 DEBUG: CecLogMessage - Changed key select (0) D:0ms cur:ff
7512020-03-10 21:54:10.716 T:3701470080 DEBUG: CecLogMessage - key pressed: select (0, 0)
7522020-03-10 21:54:10.716 T:3701470080 DEBUG: PushCecKeypress - received key b duration 0
7532020-03-10 21:54:10.716 T:3701470080 DEBUG: CecLogMessage - CheckKeypressTimeout T:340460.247
7542020-03-10 21:54:10.716 T:3701470080 DEBUG: CecLogMessage - Key select: idle (duration:0) (0) timeout:-618677112ms (rel:500,rep:0,prs:500,rel:0)
7552020-03-10 21:54:10.716 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
7562020-03-10 21:54:10.724 T:4092502032 DEBUG: HandleKey: 11 (0x0b, obc244) pressed, action is Select
7572020-03-10 21:54:10.732 T:4059034496 INFO: CActiveAESink::OpenSink - initialize sink
7582020-03-10 21:54:10.732 T:4059034496 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:default
7592020-03-10 21:54:10.732 T:4059034496 INFO: CAESinkALSA::Initialize - Requested layout: FL, FR
7602020-03-10 21:54:10.733 T:4059034496 INFO: CAESinkALSA::Initialize - Attempting to open device "default"
7612020-03-10 21:54:10.753 T:4059034496 INFO: CAESinkALSA::Initialize - Opened device "default"
7622020-03-10 21:54:10.758 T:4059034496 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
7632020-03-10 21:54:10.759 T:4059034496 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
7642020-03-10 21:54:10.759 T:4059034496 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 4096, bufferSize 16384
7652020-03-10 21:54:10.772 T:4059034496 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 4096, bufferSize 16384
7662020-03-10 21:54:10.773 T:4059034496 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 372 ms
7672020-03-10 21:54:10.773 T:4059034496 INFO: CAESinkALSA::Initialize - setting default aud_ch to 0
7682020-03-10 21:54:10.774 T:4059034496 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
7692020-03-10 21:54:10.774 T:4059034496 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR
7702020-03-10 21:54:10.775 T:4059034496 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: none) (CEA: 0)
7712020-03-10 21:54:10.775 T:4059034496 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
7722020-03-10 21:54:10.775 T:4059034496 DEBUG: Output Device : Default (AML-M8AUDIO Analog)
7732020-03-10 21:54:10.775 T:4059034496 DEBUG: Sample Rate : 44100
7742020-03-10 21:54:10.775 T:4059034496 DEBUG: Sample Format : AE_FMT_S32NE
7752020-03-10 21:54:10.775 T:4059034496 DEBUG: Channel Count : 2
7762020-03-10 21:54:10.776 T:4059034496 DEBUG: Channel Layout: FL, FR
7772020-03-10 21:54:10.776 T:4059034496 DEBUG: Frames : 4096
7782020-03-10 21:54:10.776 T:4059034496 DEBUG: Frame Size : 8
7792020-03-10 21:54:10.839 T:3701470080 DEBUG: CecLogMessage - >> 01:45
7802020-03-10 21:54:10.839 T:3701470080 DEBUG: CecLogMessage - key released: select (0) D:125ms
7812020-03-10 21:54:10.839 T:3701470080 DEBUG: PushCecKeypress - received key b duration 125
7822020-03-10 21:54:10.839 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
7832020-03-10 21:54:11.032 T:4092502032 DEBUG: ------ Window Deinit (DialogConfirm.xml) ------
7842020-03-10 21:54:11.513 T:3701470080 DEBUG: CecLogMessage - >> 01
7852020-03-10 21:54:11.513 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
7862020-03-10 21:54:13.014 T:3701470080 DEBUG: CecLogMessage - >> 01
7872020-03-10 21:54:13.014 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
7882020-03-10 21:54:14.024 T:3701470080 DEBUG: CecLogMessage - >> 01:44:01
7892020-03-10 21:54:14.024 T:3701470080 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1
7902020-03-10 21:54:14.025 T:3701470080 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0)
7912020-03-10 21:54:14.025 T:3701470080 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff
7922020-03-10 21:54:14.025 T:3701470080 DEBUG: CecLogMessage - key pressed: up (1, 0)
7932020-03-10 21:54:14.025 T:3701470080 DEBUG: PushCecKeypress - received key a6 duration 0
7942020-03-10 21:54:14.026 T:3701470080 DEBUG: CecLogMessage - CheckKeypressTimeout T:340463.557
7952020-03-10 21:54:14.026 T:3701470080 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-618677112ms (rel:500,rep:0,prs:500,rel:0)
7962020-03-10 21:54:14.026 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
7972020-03-10 21:54:14.084 T:4092502032 DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up
7982020-03-10 21:54:14.168 T:3701470080 DEBUG: CecLogMessage - >> 01:45
7992020-03-10 21:54:14.168 T:3701470080 DEBUG: CecLogMessage - key released: up (1) D:144ms
8002020-03-10 21:54:14.168 T:3701470080 DEBUG: PushCecKeypress - received key a6 duration 144
8012020-03-10 21:54:14.168 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
8022020-03-10 21:54:14.515 T:3701470080 DEBUG: CecLogMessage - >> 01
8032020-03-10 21:54:14.515 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8042020-03-10 21:54:15.165 T:3701470080 DEBUG: CecLogMessage - >> 01:44:04
8052020-03-10 21:54:15.166 T:3701470080 DEBUG: CecLogMessage - SetCurrentButton right (4) D:0ms cur:4
8062020-03-10 21:54:15.166 T:3701470080 DEBUG: CecLogMessage - key pressed: right (4) current(ff) duration(0)
8072020-03-10 21:54:15.166 T:3701470080 DEBUG: CecLogMessage - Changed key right (4) D:0ms cur:ff
8082020-03-10 21:54:15.166 T:3701470080 DEBUG: CecLogMessage - key pressed: right (4, 0)
8092020-03-10 21:54:15.166 T:3701470080 DEBUG: PushCecKeypress - received key a8 duration 0
8102020-03-10 21:54:15.166 T:3701470080 DEBUG: CecLogMessage - CheckKeypressTimeout T:340464.699
8112020-03-10 21:54:15.166 T:3701470080 DEBUG: CecLogMessage - Key right: idle (duration:0) (4) timeout:-618677112ms (rel:499,rep:0,prs:499,rel:0)
8122020-03-10 21:54:15.167 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
8132020-03-10 21:54:15.180 T:4092502032 DEBUG: HandleKey: 168 (0xa8, obc87) pressed, action is Right
8142020-03-10 21:54:15.290 T:3701470080 DEBUG: CecLogMessage - >> 01:45
8152020-03-10 21:54:15.290 T:3701470080 DEBUG: CecLogMessage - key released: right (4) D:125ms
8162020-03-10 21:54:15.290 T:3701470080 DEBUG: PushCecKeypress - received key a8 duration 125
8172020-03-10 21:54:15.290 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
8182020-03-10 21:54:16.015 T:3701470080 DEBUG: CecLogMessage - >> 01
8192020-03-10 21:54:16.016 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8202020-03-10 21:54:17.245 T:3701470080 DEBUG: CecLogMessage - >> 01:44:00
8212020-03-10 21:54:17.245 T:3701470080 DEBUG: CecLogMessage - SetCurrentButton select (0) D:0ms cur:0
8222020-03-10 21:54:17.245 T:3701470080 DEBUG: CecLogMessage - key pressed: select (0) current(ff) duration(0)
8232020-03-10 21:54:17.245 T:3701470080 DEBUG: CecLogMessage - Changed key select (0) D:0ms cur:ff
8242020-03-10 21:54:17.245 T:3701470080 DEBUG: CecLogMessage - key pressed: select (0, 0)
8252020-03-10 21:54:17.245 T:3701470080 DEBUG: PushCecKeypress - received key b duration 0
8262020-03-10 21:54:17.245 T:3701470080 DEBUG: CecLogMessage - CheckKeypressTimeout T:340466.778
8272020-03-10 21:54:17.245 T:3701470080 DEBUG: CecLogMessage - Key select: idle (duration:0) (0) timeout:-618677112ms (rel:500,rep:0,prs:500,rel:0)
8282020-03-10 21:54:17.245 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
8292020-03-10 21:54:17.277 T:4092502032 DEBUG: HandleKey: 11 (0x0b, obc244) pressed, action is Select
8302020-03-10 21:54:17.278 T:4092502032 DEBUG: Activating window ID: 10004
8312020-03-10 21:54:17.340 T:3701470080 DEBUG: CecLogMessage - >> 01:45
8322020-03-10 21:54:17.340 T:3701470080 DEBUG: CecLogMessage - key released: select (0) D:95ms
8332020-03-10 21:54:17.340 T:3701470080 DEBUG: PushCecKeypress - received key b duration 95
8342020-03-10 21:54:17.340 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
8352020-03-10 21:54:17.495 T:3987919744 DEBUG: Thread JobWorker 3987919744 terminating (autodelete)
8362020-03-10 21:54:17.516 T:3701470080 DEBUG: CecLogMessage - >> 01
8372020-03-10 21:54:17.516 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8382020-03-10 21:54:17.521 T:3879011200 DEBUG: Thread JobWorker 3879011200 terminating (autodelete)
8392020-03-10 21:54:17.638 T:3979527040 DEBUG: Thread JobWorker 3979527040 terminating (autodelete)
8402020-03-10 21:54:17.655 T:4092502032 DEBUG: ------ Window Deinit (Home.xml) ------
8412020-03-10 21:54:17.655 T:4092502032 DEBUG: FreeVisualisation() done
8422020-03-10 21:54:17.656 T:4092502032 DEBUG: ------ Window Init (Settings.xml) ------
8432020-03-10 21:54:17.656 T:4092502032 INFO: Loading skin file: Settings.xml, load type: KEEP_IN_MEMORY
8442020-03-10 21:54:17.723 T:3971134336 DEBUG: Thread JobWorker 3971134336 terminating (autodelete)
8452020-03-10 21:54:19.017 T:3701470080 DEBUG: CecLogMessage - >> 01
8462020-03-10 21:54:19.017 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8472020-03-10 21:54:20.517 T:3701470080 DEBUG: CecLogMessage - >> 01
8482020-03-10 21:54:20.517 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8492020-03-10 21:54:21.018 T:3701470080 DEBUG: CecLogMessage - >> 01:44:0d
8502020-03-10 21:54:21.019 T:3701470080 DEBUG: CecLogMessage - SetCurrentButton exit (d) D:0ms cur:d
8512020-03-10 21:54:21.019 T:3701470080 DEBUG: CecLogMessage - key pressed: exit (d) current(ff) duration(0)
8522020-03-10 21:54:21.019 T:3701470080 DEBUG: CecLogMessage - Changed key exit (d) D:0ms cur:ff
8532020-03-10 21:54:21.019 T:3701470080 DEBUG: CecLogMessage - key pressed: exit (d, 0)
8542020-03-10 21:54:21.020 T:3701470080 DEBUG: PushCecKeypress - received key d8 duration 0
8552020-03-10 21:54:21.020 T:3701470080 DEBUG: CecLogMessage - CheckKeypressTimeout T:340470.551
8562020-03-10 21:54:21.020 T:3701470080 DEBUG: CecLogMessage - Key exit: idle (duration:0) (d) timeout:-618677112ms (rel:500,rep:0,prs:500,rel:0)
8572020-03-10 21:54:21.020 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
8582020-03-10 21:54:21.034 T:4092502032 DEBUG: HandleKey: menu (0xd8) pressed, action is PreviousMenu
8592020-03-10 21:54:21.036 T:4092502032 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
8602020-03-10 21:54:21.143 T:3701470080 DEBUG: CecLogMessage - >> 01:45
8612020-03-10 21:54:21.143 T:3701470080 DEBUG: CecLogMessage - key released: exit (d) D:125ms
8622020-03-10 21:54:21.143 T:3701470080 DEBUG: PushCecKeypress - received key d8 duration 125
8632020-03-10 21:54:21.143 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
8642020-03-10 21:54:21.404 T:4092502032 DEBUG: ------ Window Deinit (Settings.xml) ------
8652020-03-10 21:54:21.404 T:4092502032 DEBUG: FreeVisualisation() done
8662020-03-10 21:54:21.404 T:4092502032 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
8672020-03-10 21:54:21.405 T:4092502032 DEBUG: ------ Window Init (Home.xml) ------
8682020-03-10 21:54:22.017 T:3701470080 DEBUG: CecLogMessage - >> 01
8692020-03-10 21:54:22.018 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8702020-03-10 21:54:23.518 T:3701470080 DEBUG: CecLogMessage - >> 01
8712020-03-10 21:54:23.518 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8722020-03-10 21:54:25.013 T:3701470080 DEBUG: CecLogMessage - >> 0f:36
8732020-03-10 21:54:25.013 T:3701470080 DEBUG: CecLogMessage - TV (0): power status changed from 'on' to 'standby'
8742020-03-10 21:54:25.013 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): standby (36)
8752020-03-10 21:54:25.063 T:3701470080 DEBUG: CecLogMessage - >> 01
8762020-03-10 21:54:25.063 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8772020-03-10 21:54:26.543 T:3701470080 DEBUG: CecLogMessage - >> 01
8782020-03-10 21:54:26.543 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8792020-03-10 21:54:28.044 T:3701470080 DEBUG: CecLogMessage - >> 01
8802020-03-10 21:54:28.044 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8812020-03-10 21:54:37.896 T:3701470080 DEBUG: CecLogMessage - >> 01
8822020-03-10 21:54:37.896 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8832020-03-10 21:54:39.397 T:3701470080 DEBUG: CecLogMessage - >> 01
8842020-03-10 21:54:39.397 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8852020-03-10 21:54:40.897 T:3701470080 DEBUG: CecLogMessage - >> 01
8862020-03-10 21:54:40.897 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8872020-03-10 21:54:42.398 T:3701470080 DEBUG: CecLogMessage - >> 01
8882020-03-10 21:54:42.398 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8892020-03-10 21:54:43.898 T:3701470080 DEBUG: CecLogMessage - >> 01
8902020-03-10 21:54:43.899 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8912020-03-10 21:54:45.399 T:3701470080 DEBUG: CecLogMessage - >> 01
8922020-03-10 21:54:45.399 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8932020-03-10 21:54:46.911 T:3701470080 DEBUG: CecLogMessage - >> 01
8942020-03-10 21:54:46.912 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8952020-03-10 21:54:48.428 T:3701470080 DEBUG: CecLogMessage - >> 01
8962020-03-10 21:54:48.428 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8972020-03-10 21:54:49.929 T:3701470080 DEBUG: CecLogMessage - >> 01
8982020-03-10 21:54:49.929 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
8992020-03-10 21:54:50.556 T:3701470080 DEBUG: CecLogMessage - >> 0f:80:ff:ff:50:00
9002020-03-10 21:54:50.556 T:3701470080 DEBUG: CecLogMessage - TV (0): power status changed from 'standby' to 'on'
9012020-03-10 21:54:50.556 T:3701470080 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
9022020-03-10 21:54:50.556 T:3701470080 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
9032020-03-10 21:54:50.556 T:3701470080 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
9042020-03-10 21:54:50.557 T:3701470080 DEBUG: CecLogMessage - << requesting vendor ID of 'TV' (0)
9052020-03-10 21:54:50.557 T:3701470080 DEBUG: CecLogMessage - << 10:8c
9062020-03-10 21:54:50.557 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
9072020-03-10 21:54:51.658 T:3701470080 DEBUG: CecLogMessage - expected response not received (87: device vendor id)
9082020-03-10 21:54:51.658 T:3701470080 DEBUG: CecLogMessage - << 10:8c
9092020-03-10 21:54:52.788 T:3701470080 DEBUG: CecLogMessage - expected response not received (87: device vendor id)
9102020-03-10 21:54:52.788 T:3701470080 DEBUG: CecLogMessage - << powering on 'TV' (0)
9112020-03-10 21:54:52.788 T:3701470080 DEBUG: CecLogMessage - << 10:04
9122020-03-10 21:54:52.878 T:3701470080 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (5000)
9132020-03-10 21:54:52.878 T:3701470080 DEBUG: CecLogMessage - << 1f:82:50:00
9142020-03-10 21:54:53.018 T:3701470080 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
9152020-03-10 21:54:53.018 T:3701470080 DEBUG: CecLogMessage - << 10:8e:00
9162020-03-10 21:54:53.156 T:3701470080 DEBUG: CecLogMessage - >> 01:83
9172020-03-10 21:54:53.157 T:3701470080 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): physical address 5000
9182020-03-10 21:54:53.157 T:3701470080 DEBUG: CecLogMessage - << 1f:84:50:00:01
9192020-03-10 21:54:53.157 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give physical address (83)
9202020-03-10 21:54:53.346 T:3701470080 DEBUG: CecLogMessage - >> 01
9212020-03-10 21:54:53.347 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9222020-03-10 21:54:53.347 T:3701470080 DEBUG: CecLogMessage - >> 01
9232020-03-10 21:54:53.347 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9242020-03-10 21:54:53.441 T:3701470080 DEBUG: CecLogMessage - >> 01:46
9252020-03-10 21:54:53.442 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give osd name (46)
9262020-03-10 21:54:53.444 T:3701470080 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): OSD name 'CoreELEC'
9272020-03-10 21:54:53.444 T:3701470080 DEBUG: CecLogMessage - << 10:47:43:6f:72:65:45:4c:45:43
9282020-03-10 21:54:54.431 T:3701470080 DEBUG: CecLogMessage - >> 01
9292020-03-10 21:54:54.431 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9302020-03-10 21:54:55.931 T:3701470080 DEBUG: CecLogMessage - >> 01
9312020-03-10 21:54:55.931 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9322020-03-10 21:54:57.432 T:3701470080 DEBUG: CecLogMessage - >> 01
9332020-03-10 21:54:57.432 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9342020-03-10 21:54:58.933 T:3701470080 DEBUG: CecLogMessage - >> 01
9352020-03-10 21:54:58.933 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9362020-03-10 21:55:00.433 T:3701470080 DEBUG: CecLogMessage - >> 01
9372020-03-10 21:55:00.434 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9382020-03-10 21:55:01.934 T:3701470080 DEBUG: CecLogMessage - >> 01
9392020-03-10 21:55:01.934 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9402020-03-10 21:55:03.435 T:3701470080 DEBUG: CecLogMessage - >> 01
9412020-03-10 21:55:03.435 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9422020-03-10 21:55:04.935 T:3701470080 DEBUG: CecLogMessage - >> 01
9432020-03-10 21:55:04.935 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9442020-03-10 21:55:06.436 T:3701470080 DEBUG: CecLogMessage - >> 01
9452020-03-10 21:55:06.436 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9462020-03-10 21:55:07.936 T:3701470080 DEBUG: CecLogMessage - >> 01
9472020-03-10 21:55:07.936 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9482020-03-10 21:55:09.436 T:3701470080 DEBUG: CecLogMessage - >> 01
9492020-03-10 21:55:09.436 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9502020-03-10 21:55:10.937 T:3701470080 DEBUG: CecLogMessage - >> 01
9512020-03-10 21:55:10.937 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9522020-03-10 21:55:12.438 T:3701470080 DEBUG: CecLogMessage - >> 01
9532020-03-10 21:55:12.438 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9542020-03-10 21:55:13.939 T:3701470080 DEBUG: CecLogMessage - >> 01
9552020-03-10 21:55:13.939 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9562020-03-10 21:55:15.439 T:3701470080 DEBUG: CecLogMessage - >> 01
9572020-03-10 21:55:15.439 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9582020-03-10 21:55:16.939 T:3701470080 DEBUG: CecLogMessage - >> 01
9592020-03-10 21:55:16.940 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9602020-03-10 21:55:18.440 T:3701470080 DEBUG: CecLogMessage - >> 01
9612020-03-10 21:55:18.440 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9622020-03-10 21:55:19.941 T:3701470080 DEBUG: CecLogMessage - >> 01
9632020-03-10 21:55:19.941 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9642020-03-10 21:55:21.442 T:3701470080 DEBUG: CecLogMessage - >> 01
9652020-03-10 21:55:21.442 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9662020-03-10 21:55:21.770 T:4092502032 NOTICE: Samba is idle. Closing the remaining connections
9672020-03-10 21:55:22.942 T:3701470080 DEBUG: CecLogMessage - >> 01
9682020-03-10 21:55:22.942 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9692020-03-10 21:55:24.442 T:3701470080 DEBUG: CecLogMessage - >> 01
9702020-03-10 21:55:24.442 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9712020-03-10 21:55:25.943 T:3701470080 DEBUG: CecLogMessage - >> 01
9722020-03-10 21:55:25.943 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9732020-03-10 21:55:27.444 T:3701470080 DEBUG: CecLogMessage - >> 01
9742020-03-10 21:55:27.444 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9752020-03-10 21:55:28.944 T:3701470080 DEBUG: CecLogMessage - >> 01
9762020-03-10 21:55:28.944 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9772020-03-10 21:55:30.445 T:3701470080 DEBUG: CecLogMessage - >> 01
9782020-03-10 21:55:30.445 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9792020-03-10 21:55:31.945 T:3701470080 DEBUG: CecLogMessage - >> 01
9802020-03-10 21:55:31.946 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9812020-03-10 21:55:33.446 T:3701470080 DEBUG: CecLogMessage - >> 01
9822020-03-10 21:55:33.446 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9832020-03-10 21:55:34.946 T:3701470080 DEBUG: CecLogMessage - >> 01
9842020-03-10 21:55:34.946 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9852020-03-10 21:55:36.447 T:3701470080 DEBUG: CecLogMessage - >> 01
9862020-03-10 21:55:36.447 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9872020-03-10 21:55:37.947 T:3701470080 DEBUG: CecLogMessage - >> 01
9882020-03-10 21:55:37.948 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9892020-03-10 21:55:39.448 T:3701470080 DEBUG: CecLogMessage - >> 01
9902020-03-10 21:55:39.448 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9912020-03-10 21:55:40.949 T:3701470080 DEBUG: CecLogMessage - >> 01
9922020-03-10 21:55:40.949 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
9932020-03-10 21:55:41.225 T:3701470080 DEBUG: CecLogMessage - >> 0f:80:50:00:00:00
9942020-03-10 21:55:41.225 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
9952020-03-10 21:55:41.349 T:3701470080 DEBUG: CecLogMessage - >> 0f:82:00:00
9962020-03-10 21:55:41.349 T:3701470080 DEBUG: CecLogMessage - making TV (0) the active source
9972020-03-10 21:55:41.349 T:3701470080 DEBUG: CecLogMessage - marking Recorder 1 (1) as inactive source
9982020-03-10 21:55:41.349 T:3701470080 DEBUG: CecLogMessage - >> source deactivated: Recorder 1 (1)
9992020-03-10 21:55:41.389 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): active source (82)
10002020-03-10 21:55:42.449 T:3701470080 DEBUG: CecLogMessage - >> 01
10012020-03-10 21:55:42.449 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10022020-03-10 21:55:43.950 T:3701470080 DEBUG: CecLogMessage - >> 01
10032020-03-10 21:55:43.950 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10042020-03-10 21:55:45.450 T:3701470080 DEBUG: CecLogMessage - >> 01
10052020-03-10 21:55:45.450 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10062020-03-10 21:55:46.951 T:3701470080 DEBUG: CecLogMessage - >> 01
10072020-03-10 21:55:46.952 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10082020-03-10 21:55:48.452 T:3701470080 DEBUG: CecLogMessage - >> 01
10092020-03-10 21:55:48.452 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10102020-03-10 21:55:49.952 T:3701470080 DEBUG: CecLogMessage - >> 01
10112020-03-10 21:55:49.952 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10122020-03-10 21:55:51.453 T:3701470080 DEBUG: CecLogMessage - >> 01
10132020-03-10 21:55:51.453 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10142020-03-10 21:55:52.953 T:3701470080 DEBUG: CecLogMessage - >> 01
10152020-03-10 21:55:52.953 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10162020-03-10 21:55:54.454 T:3701470080 DEBUG: CecLogMessage - >> 01
10172020-03-10 21:55:54.454 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10182020-03-10 21:55:55.955 T:3701470080 DEBUG: CecLogMessage - >> 01
10192020-03-10 21:55:55.955 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10202020-03-10 21:55:57.455 T:3701470080 DEBUG: CecLogMessage - >> 01
10212020-03-10 21:55:57.456 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10222020-03-10 21:55:58.956 T:3701470080 DEBUG: CecLogMessage - >> 01
10232020-03-10 21:55:58.956 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10242020-03-10 21:56:00.456 T:3701470080 DEBUG: CecLogMessage - >> 01
10252020-03-10 21:56:00.456 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10262020-03-10 21:56:01.957 T:3701470080 DEBUG: CecLogMessage - >> 01
10272020-03-10 21:56:01.957 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10282020-03-10 21:56:03.457 T:3701470080 DEBUG: CecLogMessage - >> 01
10292020-03-10 21:56:03.458 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10302020-03-10 21:56:04.958 T:3701470080 DEBUG: CecLogMessage - >> 01
10312020-03-10 21:56:04.958 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10322020-03-10 21:56:06.458 T:3701470080 DEBUG: CecLogMessage - >> 01
10332020-03-10 21:56:06.458 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10342020-03-10 21:56:07.959 T:3701470080 DEBUG: CecLogMessage - >> 01
10352020-03-10 21:56:07.959 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10362020-03-10 21:56:09.459 T:3701470080 DEBUG: CecLogMessage - >> 01
10372020-03-10 21:56:09.460 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10382020-03-10 21:56:10.960 T:3701470080 DEBUG: CecLogMessage - >> 01
10392020-03-10 21:56:10.961 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10402020-03-10 21:56:12.461 T:3701470080 DEBUG: CecLogMessage - >> 01
10412020-03-10 21:56:12.461 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10422020-03-10 21:56:13.961 T:3701470080 DEBUG: CecLogMessage - >> 01
10432020-03-10 21:56:13.962 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10442020-03-10 21:56:15.462 T:3701470080 DEBUG: CecLogMessage - >> 01
10452020-03-10 21:56:15.462 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10462020-03-10 21:56:16.963 T:3701470080 DEBUG: CecLogMessage - >> 01
10472020-03-10 21:56:16.963 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10482020-03-10 21:56:18.463 T:3701470080 DEBUG: CecLogMessage - >> 01
10492020-03-10 21:56:18.463 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10502020-03-10 21:56:19.963 T:3701470080 DEBUG: CecLogMessage - >> 01
10512020-03-10 21:56:19.963 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10522020-03-10 21:56:21.464 T:3701470080 DEBUG: CecLogMessage - >> 01
10532020-03-10 21:56:21.464 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10542020-03-10 21:56:22.965 T:3701470080 DEBUG: CecLogMessage - >> 01
10552020-03-10 21:56:22.965 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10562020-03-10 21:56:24.466 T:3701470080 DEBUG: CecLogMessage - >> 01
10572020-03-10 21:56:24.466 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10582020-03-10 21:56:25.966 T:3701470080 DEBUG: CecLogMessage - >> 01
10592020-03-10 21:56:25.966 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
1060---This is where the input is switched back to Kodi and CEC/remote starts to work
10612020-03-10 21:56:27.234 T:3701470080 DEBUG: CecLogMessage - >> 0f:86:50:00
10622020-03-10 21:56:27.234 T:3701470080 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 5000
10632020-03-10 21:56:27.235 T:3701470080 DEBUG: CecLogMessage - making Recorder 1 (1) the active source
10642020-03-10 21:56:27.235 T:3701470080 DEBUG: CecLogMessage - marking TV (0) as inactive source
10652020-03-10 21:56:27.235 T:3701470080 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
10662020-03-10 21:56:27.235 T:3701470080 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
10672020-03-10 21:56:27.235 T:3701470080 DEBUG: CecLogMessage - << requesting vendor ID of 'TV' (0)
10682020-03-10 21:56:27.235 T:3701470080 DEBUG: CecLogMessage - << 10:8c
10692020-03-10 21:56:27.235 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
10702020-03-10 21:56:28.337 T:3701470080 DEBUG: CecLogMessage - expected response not received (87: device vendor id)
10712020-03-10 21:56:28.337 T:3701470080 DEBUG: CecLogMessage - << 10:8c
10722020-03-10 21:56:29.428 T:3701470080 DEBUG: CecLogMessage - expected response not received (87: device vendor id)
10732020-03-10 21:56:29.428 T:3701470080 DEBUG: CecLogMessage - << powering on 'TV' (0)
10742020-03-10 21:56:29.428 T:3701470080 DEBUG: CecLogMessage - << 10:04
10752020-03-10 21:56:29.518 T:3701470080 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (5000)
10762020-03-10 21:56:29.518 T:3701470080 DEBUG: CecLogMessage - << 1f:82:50:00
10772020-03-10 21:56:29.688 T:3701470080 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
10782020-03-10 21:56:29.688 T:3701470080 DEBUG: CecLogMessage - << 10:8e:00
10792020-03-10 21:56:29.828 T:3701470080 DEBUG: CecLogMessage - >> 01
10802020-03-10 21:56:29.829 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10812020-03-10 21:56:29.829 T:3701470080 DEBUG: CecLogMessage - >> 0f:80:00:00:50:00
10822020-03-10 21:56:29.829 T:3701470080 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
10832020-03-10 21:56:29.829 T:3701470080 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
10842020-03-10 21:56:29.830 T:3701470080 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
10852020-03-10 21:56:29.830 T:3701470080 DEBUG: CecLogMessage - << requesting vendor ID of 'TV' (0)
10862020-03-10 21:56:29.830 T:3701470080 DEBUG: CecLogMessage - << 10:8c
10872020-03-10 21:56:29.830 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
10882020-03-10 21:56:30.948 T:3701470080 DEBUG: CecLogMessage - expected response not received (87: device vendor id)
10892020-03-10 21:56:30.948 T:3701470080 DEBUG: CecLogMessage - << 10:8c
10902020-03-10 21:56:32.048 T:3701470080 DEBUG: CecLogMessage - expected response not received (87: device vendor id)
10912020-03-10 21:56:32.048 T:3701470080 DEBUG: CecLogMessage - << powering on 'TV' (0)
10922020-03-10 21:56:32.048 T:3701470080 DEBUG: CecLogMessage - << 10:04
10932020-03-10 21:56:32.178 T:3701470080 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (5000)
10942020-03-10 21:56:32.178 T:3701470080 DEBUG: CecLogMessage - << 1f:82:50:00
10952020-03-10 21:56:32.338 T:3701470080 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
10962020-03-10 21:56:32.338 T:3701470080 DEBUG: CecLogMessage - << 10:8e:00
10972020-03-10 21:56:32.487 T:3701470080 DEBUG: CecLogMessage - >> 01
10982020-03-10 21:56:32.488 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
10992020-03-10 21:56:32.488 T:3701470080 DEBUG: CecLogMessage - >> 01
11002020-03-10 21:56:32.488 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
11012020-03-10 21:56:32.488 T:3701470080 DEBUG: CecLogMessage - >> 01:44:02
11022020-03-10 21:56:32.488 T:3701470080 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2
11032020-03-10 21:56:32.488 T:3701470080 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0)
11042020-03-10 21:56:32.488 T:3701470080 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff
11052020-03-10 21:56:32.488 T:3701470080 DEBUG: CecLogMessage - key pressed: down (2, 0)
11062020-03-10 21:56:32.488 T:3701470080 DEBUG: PushCecKeypress - received key a7 duration 0
11072020-03-10 21:56:32.489 T:3701470080 DEBUG: CecLogMessage - CheckKeypressTimeout T:340602.020
11082020-03-10 21:56:32.489 T:3701470080 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-618677112ms (rel:500,rep:0,prs:500,rel:0)
11092020-03-10 21:56:32.489 T:3701470080 DEBUG: CecLogMessage - >> 01:45
11102020-03-10 21:56:32.489 T:3701470080 DEBUG: CecLogMessage - key released: down (2) D:0ms
11112020-03-10 21:56:32.489 T:3701470080 DEBUG: PushCecKeypress - received key a7 duration 0
11122020-03-10 21:56:32.489 T:3701470080 DEBUG: CecLogMessage - >> 01:44:02
11132020-03-10 21:56:32.489 T:3701470080 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2
11142020-03-10 21:56:32.489 T:3701470080 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0)
11152020-03-10 21:56:32.489 T:3701470080 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff
11162020-03-10 21:56:32.489 T:3701470080 DEBUG: CecLogMessage - key pressed: down (2, 0)
11172020-03-10 21:56:32.490 T:3701470080 DEBUG: PushCecKeypress - received key a7 duration 0
11182020-03-10 21:56:32.490 T:3701470080 DEBUG: CecLogMessage - CheckKeypressTimeout T:340602.020
11192020-03-10 21:56:32.490 T:3701470080 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-618677112ms (rel:500,rep:0,prs:500,rel:0)
11202020-03-10 21:56:32.490 T:3701470080 DEBUG: CecLogMessage - >> 01:45
11212020-03-10 21:56:32.490 T:3701470080 DEBUG: CecLogMessage - key released: down (2) D:0ms
11222020-03-10 21:56:32.490 T:3701470080 DEBUG: PushCecKeypress - received key a7 duration 0
11232020-03-10 21:56:32.490 T:3701470080 DEBUG: CecLogMessage - >> 01
11242020-03-10 21:56:32.490 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
11252020-03-10 21:56:32.490 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
11262020-03-10 21:56:32.490 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
11272020-03-10 21:56:32.490 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
11282020-03-10 21:56:32.491 T:3701470080 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
11292020-03-10 21:56:32.517 T:4092502032 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down
11302020-03-10 21:56:32.518 T:4059034496 INFO: CActiveAESink::OpenSink - initialize sink
11312020-03-10 21:56:32.519 T:4059034496 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:default
11322020-03-10 21:56:32.519 T:4059034496 INFO: CAESinkALSA::Initialize - Requested layout: FL, FR
11332020-03-10 21:56:32.519 T:4059034496 INFO: CAESinkALSA::Initialize - Attempting to open device "default"
11342020-03-10 21:56:32.529 T:4059034496 INFO: CAESinkALSA::Initialize - Opened device "default"
11352020-03-10 21:56:32.532 T:4059034496 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
11362020-03-10 21:56:32.532 T:4059034496 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
11372020-03-10 21:56:32.532 T:4059034496 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 4096, bufferSize 16384
11382020-03-10 21:56:32.536 T:4092502032 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down
11392020-03-10 21:56:32.537 T:4059034496 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 4096, bufferSize 16384
11402020-03-10 21:56:32.537 T:4059034496 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 372 ms
11412020-03-10 21:56:32.537 T:4059034496 INFO: CAESinkALSA::Initialize - setting default aud_ch to 0
11422020-03-10 21:56:32.538 T:4059034496 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
11432020-03-10 21:56:32.538 T:4059034496 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR
11442020-03-10 21:56:32.538 T:4059034496 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: none) (CEA: 0)
11452020-03-10 21:56:32.538 T:4059034496 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
11462020-03-10 21:56:32.538 T:4059034496 DEBUG: Output Device : Default (AML-M8AUDIO Analog)
11472020-03-10 21:56:32.538 T:4059034496 DEBUG: Sample Rate : 44100
11482020-03-10 21:56:32.538 T:4059034496 DEBUG: Sample Format : AE_FMT_S32NE
11492020-03-10 21:56:32.539 T:4059034496 DEBUG: Channel Count : 2
11502020-03-10 21:56:32.539 T:4059034496 DEBUG: Channel Layout: FL, FR
11512020-03-10 21:56:32.539 T:4059034496 DEBUG: Frames : 4096
11522020-03-10 21:56:32.539 T:4059034496 DEBUG: Frame Size : 8
11532020-03-10 21:56:32.559 T:3609150336 DEBUG: Thread JobWorker start, auto delete: true
11542020-03-10 21:56:32.598 T:3609150336 DEBUG: Previous line repeats 3 times.
11552020-03-10 21:56:32.599 T:3609150336 DEBUG: ffmpeg[D71F4380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11562020-03-10 21:56:32.605 T:3987919744 DEBUG: ffmpeg[EDB2D380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11572020-03-10 21:56:32.607 T:3879150464 DEBUG: ffmpeg[E7372380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11582020-03-10 21:56:32.624 T:3987919744 DEBUG: ffmpeg[EDB2D380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11592020-03-10 21:56:32.627 T:3978326912 DEBUG: ffmpeg[ED207380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11602020-03-10 21:56:32.628 T:3879150464 DEBUG: ffmpeg[E7372380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11612020-03-10 21:56:32.629 T:3609150336 DEBUG: ffmpeg[D71F4380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11622020-03-10 21:56:32.651 T:4092502032 DEBUG: Previous line repeats 1 times.
11632020-03-10 21:56:32.651 T:4092502032 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down
11642020-03-10 21:56:32.653 T:3978326912 DEBUG: ffmpeg[ED207380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11652020-03-10 21:56:32.656 T:3879150464 DEBUG: ffmpeg[E7372380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11662020-03-10 21:56:32.658 T:3987919744 DEBUG: ffmpeg[EDB2D380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11672020-03-10 21:56:32.682 T:3609150336 DEBUG: ffmpeg[D71F4380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11682020-03-10 21:56:32.684 T:3978326912 DEBUG: ffmpeg[ED207380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11692020-03-10 21:56:32.700 T:3879150464 DEBUG: ffmpeg[E7372380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11702020-03-10 21:56:32.708 T:3978326912 DEBUG: ffmpeg[ED207380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11712020-03-10 21:56:32.712 T:3609150336 DEBUG: ffmpeg[D71F4380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11722020-03-10 21:56:32.724 T:3987919744 DEBUG: ffmpeg[EDB2D380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11732020-03-10 21:56:32.746 T:3978326912 DEBUG: ffmpeg[ED207380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11742020-03-10 21:56:32.748 T:3609150336 DEBUG: ffmpeg[D71F4380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11752020-03-10 21:56:32.752 T:4092502032 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down
11762020-03-10 21:56:32.757 T:3879150464 DEBUG: ffmpeg[E7372380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11772020-03-10 21:56:32.776 T:3987919744 DEBUG: Previous line repeats 1 times.
11782020-03-10 21:56:32.776 T:3987919744 DEBUG: ffmpeg[EDB2D380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11792020-03-10 21:56:32.777 T:3609150336 DEBUG: ffmpeg[D71F4380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11802020-03-10 21:56:32.801 T:3978326912 DEBUG: ffmpeg[ED207380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11812020-03-10 21:56:32.812 T:3879150464 DEBUG: ffmpeg[E7372380]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
11822020-03-10 21:56:33.469 T:3701470080 DEBUG: CecLogMessage - >> 01
11832020-03-10 21:56:33.470 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
11842020-03-10 21:56:34.970 T:3701470080 DEBUG: CecLogMessage - >> 01
11852020-03-10 21:56:34.970 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
11862020-03-10 21:56:36.470 T:3701470080 DEBUG: CecLogMessage - >> 01
11872020-03-10 21:56:36.470 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
11882020-03-10 21:56:37.971 T:3701470080 DEBUG: CecLogMessage - >> 01
11892020-03-10 21:56:37.971 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
11902020-03-10 21:56:39.471 T:3701470080 DEBUG: CecLogMessage - >> 01
11912020-03-10 21:56:39.471 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
11922020-03-10 21:56:40.972 T:3701470080 DEBUG: CecLogMessage - >> 01
11932020-03-10 21:56:40.972 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
11942020-03-10 21:56:42.473 T:3701470080 DEBUG: CecLogMessage - >> 01
11952020-03-10 21:56:42.473 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)
11962020-03-10 21:56:43.973 T:3701470080 DEBUG: CecLogMessage - >> 01
11972020-03-10 21:56:43.973 T:3701470080 DEBUG: CecLogMessage - << POLL: TV (0) -> Recorder 1 (1)