2021-03-23 18:31:48.259 T:925 INFO : ----------------------------------------------------------------------- 2021-03-23 18:31:48.260 T:925 INFO : Starting Kodi (19.0 (19.0.0) Git:19.0-Matrix). Platform: Linux ARM 32-bit 2021-03-23 18:31:48.260 T:925 INFO : Using Release Kodi x32 2021-03-23 18:31:48.260 T:925 INFO : Kodi compiled 2021-03-11 by GCC 10.2.0 for Linux ARM 32-bit version 5.10.17 (330257) 2021-03-23 18:31:48.260 T:925 INFO : Running on BCM2711 with LibreELEC (official): 9.95.1 10.0, kernel: Linux ARM 32-bit version 5.10.17 2021-03-23 18:31:48.260 T:925 INFO : FFmpeg version/source: 4.3.1-Kodi 2021-03-23 18:31:48.260 T:925 INFO : Host CPU: ARMv7 Processor rev 3 (v7l), 4 cores available 2021-03-23 18:31:48.260 T:925 INFO : ARM Features: Neon enabled 2021-03-23 18:31:48.260 T:925 INFO : special://xbmc/ is mapped to: /usr/share/kodi/ 2021-03-23 18:31:48.260 T:925 INFO : special://xbmcbin/ is mapped to: /usr/lib/kodi 2021-03-23 18:31:48.260 T:925 INFO : special://xbmcbinaddons/ is mapped to: /usr/lib/kodi/addons 2021-03-23 18:31:48.260 T:925 INFO : special://masterprofile/ is mapped to: /storage/.kodi/userdata 2021-03-23 18:31:48.260 T:925 INFO : special://envhome/ is mapped to: /storage 2021-03-23 18:31:48.260 T:925 INFO : special://home/ is mapped to: /storage/.kodi 2021-03-23 18:31:48.260 T:925 INFO : special://temp/ is mapped to: /storage/.kodi/temp 2021-03-23 18:31:48.260 T:925 INFO : special://logpath/ is mapped to: /storage/.kodi/temp 2021-03-23 18:31:48.260 T:925 INFO : The executable running is: /usr/lib/kodi/kodi.bin 2021-03-23 18:31:48.260 T:925 INFO : Local hostname: media-pi04 2021-03-23 18:31:48.260 T:925 INFO : Log File is located: /storage/.kodi/temp/kodi.log 2021-03-23 18:31:48.260 T:925 INFO : ----------------------------------------------------------------------- 2021-03-23 18:31:48.260 T:925 INFO : loading settings 2021-03-23 18:31:48.262 T:925 INFO : special://profile/ is mapped to: special://masterprofile/ 2021-03-23 18:31:48.293 T:925 INFO : Loaded settings file from special://xbmc/system/advancedsettings.xml 2021-03-23 18:31:48.294 T:925 INFO : Contents of special://xbmc/system/advancedsettings.xml are... /usr/bin/cputemp /usr/bin/gputemp false 1 30 720 540 2021-03-23 18:31:48.296 T:925 INFO : Loaded settings file from special://profile/advancedsettings.xml 2021-03-23 18:31:48.297 T:925 INFO : Contents of special://profile/advancedsettings.xml are... mysql mysql-pi01 3306 kodi_user ***** mysql mysql-pi01 3306 kodi_user ***** true true 2021-03-23 18:31:48.297 T:925 WARNING : VIDEO database configuration is experimental. 2021-03-23 18:31:48.297 T:925 WARNING : missing version attribute 2021-03-23 18:31:48.298 T:925 INFO : Default Video Player: VideoPlayer 2021-03-23 18:31:48.298 T:925 INFO : Default Audio Player: paplayer 2021-03-23 18:31:48.298 T:925 INFO : Disabled debug logging due to GUI setting. Level 0. 2021-03-23 18:31:48.298 T:925 INFO : Log level changed to "INFO" 2021-03-23 18:31:48.298 T:925 INFO : CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml 2021-03-23 18:31:48.301 T:925 INFO : creating subdirectories 2021-03-23 18:31:48.301 T:925 INFO : userdata folder: special://masterprofile/ 2021-03-23 18:31:48.301 T:925 INFO : recording folder: 2021-03-23 18:31:48.301 T:925 INFO : screenshots folder: /storage/screenshots/ 2021-03-23 18:31:48.346 T:925 INFO : Running database version Addons33 2021-03-23 18:31:48.563 T:925 INFO : CAddonMgr::FindAddons: audioencoder.kodi.builtin.aac v1.0.2 installed 2021-03-23 18:31:48.563 T:925 INFO : CAddonMgr::FindAddons: audioencoder.kodi.builtin.wma v1.0.2 installed 2021-03-23 18:31:48.564 T:925 INFO : CAddonMgr::FindAddons: game.controller.default v1.0.9 installed 2021-03-23 18:31:48.564 T:925 INFO : CAddonMgr::FindAddons: game.controller.snes v1.0.9 installed 2021-03-23 18:31:48.564 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.global.audioengine v1.1.1 installed 2021-03-23 18:31:48.564 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.global.filesystem v1.1.6 installed 2021-03-23 18:31:48.564 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.global.general v1.0.5 installed 2021-03-23 18:31:48.564 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.global.gui v5.15.0 installed 2021-03-23 18:31:48.564 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.global.main v1.3.0 installed 2021-03-23 18:31:48.564 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.global.network v1.0.4 installed 2021-03-23 18:31:48.565 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.global.tools v1.0.4 installed 2021-03-23 18:31:48.565 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.instance.audiodecoder v3.0.0 installed 2021-03-23 18:31:48.565 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.instance.audioencoder v2.1.0 installed 2021-03-23 18:31:48.565 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.instance.game v2.1.0 installed 2021-03-23 18:31:48.565 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.instance.imagedecoder v2.1.1 installed 2021-03-23 18:31:48.565 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.instance.inputstream v3.0.1 installed 2021-03-23 18:31:48.565 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.instance.peripheral v2.0.0 installed 2021-03-23 18:31:48.565 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.instance.pvr v7.1.0 installed 2021-03-23 18:31:48.566 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.instance.screensaver v2.1.0 installed 2021-03-23 18:31:48.566 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.instance.vfs v3.0.0 installed 2021-03-23 18:31:48.566 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.instance.videocodec v2.0.2 installed 2021-03-23 18:31:48.566 T:925 INFO : CAddonMgr::FindAddons: kodi.binary.instance.visualization v3.0.0 installed 2021-03-23 18:31:48.566 T:925 INFO : CAddonMgr::FindAddons: kodi.resource v1.0.0 installed 2021-03-23 18:31:48.566 T:925 INFO : CAddonMgr::FindAddons: metadata.album.universal v3.1.4 installed 2021-03-23 18:31:48.566 T:925 INFO : CAddonMgr::FindAddons: metadata.artists.universal v4.3.3 installed 2021-03-23 18:31:48.566 T:925 INFO : CAddonMgr::FindAddons: metadata.common.allmusic.com v3.2.2 installed 2021-03-23 18:31:48.567 T:925 INFO : CAddonMgr::FindAddons: metadata.common.fanart.tv v3.6.3 installed 2021-03-23 18:31:48.567 T:925 INFO : CAddonMgr::FindAddons: metadata.common.imdb.com v3.1.6 installed 2021-03-23 18:31:48.567 T:925 INFO : CAddonMgr::FindAddons: metadata.common.musicbrainz.org v2.2.4 installed 2021-03-23 18:31:48.567 T:925 INFO : CAddonMgr::FindAddons: metadata.common.theaudiodb.com v2.0.3 installed 2021-03-23 18:31:48.567 T:925 INFO : CAddonMgr::FindAddons: metadata.common.themoviedb.org v3.2.14 installed 2021-03-23 18:31:48.567 T:925 INFO : CAddonMgr::FindAddons: metadata.generic.albums v1.0.14 installed 2021-03-23 18:31:48.567 T:925 INFO : CAddonMgr::FindAddons: metadata.generic.artists v1.0.14 installed 2021-03-23 18:31:48.567 T:925 INFO : CAddonMgr::FindAddons: metadata.local v1.0.1 installed 2021-03-23 18:31:48.568 T:925 INFO : CAddonMgr::FindAddons: metadata.themoviedb.org v5.2.6 installed 2021-03-23 18:31:48.568 T:925 INFO : CAddonMgr::FindAddons: metadata.themoviedb.org.python v1.3.2+matrix.1 installed 2021-03-23 18:31:48.568 T:925 INFO : CAddonMgr::FindAddons: metadata.tvshows.themoviedb.org v3.5.13 installed 2021-03-23 18:31:48.568 T:925 INFO : CAddonMgr::FindAddons: metadata.tvshows.themoviedb.org.python v1.4.0 installed 2021-03-23 18:31:48.568 T:925 INFO : CAddonMgr::FindAddons: peripheral.joystick v1.7.1 installed 2021-03-23 18:31:48.568 T:925 INFO : CAddonMgr::FindAddons: pvr.nextpvr v8.2.1.3 installed 2021-03-23 18:31:48.568 T:925 INFO : CAddonMgr::FindAddons: repository.kodi.game v1.0.0 installed 2021-03-23 18:31:48.568 T:925 INFO : CAddonMgr::FindAddons: repository.libreelec.tv v9.80.11 installed 2021-03-23 18:31:48.569 T:925 INFO : CAddonMgr::FindAddons: repository.xbmc.org v3.2.5 installed 2021-03-23 18:31:48.569 T:925 INFO : CAddonMgr::FindAddons: resource.images.weathericons.default v1.1.9 installed 2021-03-23 18:31:48.569 T:925 INFO : CAddonMgr::FindAddons: resource.language.en_gb v2.0.2 installed 2021-03-23 18:31:48.569 T:925 INFO : CAddonMgr::FindAddons: resource.uisounds.kodi v1.0.1 installed 2021-03-23 18:31:48.569 T:925 INFO : CAddonMgr::FindAddons: screensaver.xbmc.builtin.black v1.0.34 installed 2021-03-23 18:31:48.569 T:925 INFO : CAddonMgr::FindAddons: screensaver.xbmc.builtin.dim v1.0.64 installed 2021-03-23 18:31:48.569 T:925 INFO : CAddonMgr::FindAddons: script.module.pil v5.1.0 installed 2021-03-23 18:31:48.569 T:925 INFO : CAddonMgr::FindAddons: script.module.pycryptodome v3.4.3 installed 2021-03-23 18:31:48.570 T:925 INFO : CAddonMgr::FindAddons: service.libreelec.settings v10.0 installed 2021-03-23 18:31:48.570 T:925 INFO : CAddonMgr::FindAddons: skin.confluence v4.7.6 installed 2021-03-23 18:31:48.570 T:925 INFO : CAddonMgr::FindAddons: skin.estuary v3.0.5 installed 2021-03-23 18:31:48.570 T:925 INFO : CAddonMgr::FindAddons: webinterface.default v19.x-2.4.7 installed 2021-03-23 18:31:48.570 T:925 INFO : CAddonMgr::FindAddons: xbmc.addon v19.0.0 installed 2021-03-23 18:31:48.570 T:925 INFO : CAddonMgr::FindAddons: xbmc.core v0.1.0 installed 2021-03-23 18:31:48.570 T:925 INFO : CAddonMgr::FindAddons: xbmc.gui v5.15.0 installed 2021-03-23 18:31:48.570 T:925 INFO : CAddonMgr::FindAddons: xbmc.json v12.2.1 installed 2021-03-23 18:31:48.571 T:925 INFO : CAddonMgr::FindAddons: xbmc.metadata v2.1.0 installed 2021-03-23 18:31:48.571 T:925 INFO : CAddonMgr::FindAddons: xbmc.python v3.0.0 installed 2021-03-23 18:31:48.571 T:925 INFO : CAddonMgr::FindAddons: xbmc.webinterface v1.0.0 installed 2021-03-23 18:31:48.576 T:925 INFO : Selected Logind/UPower as PowerSyscall 2021-03-23 18:31:48.576 T:925 ERROR : DBus error: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files 2021-03-23 18:31:48.576 T:925 INFO : LogindUPowerSyscall - UPower not found, battery information will not be available 2021-03-23 18:31:48.626 T:930 INFO : CAESinkALSA - Unable to open device "sysdefault:CARD=vc4hdmi1" for playback 2021-03-23 18:31:48.627 T:930 INFO : CAESinkALSA - Unable to open device "hdmi:CARD=vc4hdmi1,DEV=0" for playback 2021-03-23 18:31:48.629 T:930 WARNING : Pulseaudio module module-allow-passthrough not loaded - opening PT devices might fail 2021-03-23 18:31:48.630 T:930 INFO : Found 2 Lists of Devices 2021-03-23 18:31:48.630 T:930 INFO : Enumerated ALSA devices: 2021-03-23 18:31:48.630 T:930 INFO : Device 1 2021-03-23 18:31:48.630 T:930 INFO : m_deviceName : default 2021-03-23 18:31:48.630 T:930 INFO : m_displayName : Default Audio Device () 2021-03-23 18:31:48.630 T:930 INFO : m_displayNameExtra: 2021-03-23 18:31:48.630 T:930 INFO : m_deviceType : AE_DEVTYPE_PCM 2021-03-23 18:31:48.630 T:930 INFO : m_channels : FL, FR 2021-03-23 18:31:48.630 T:930 INFO : m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 2021-03-23 18:31:48.630 T:930 INFO : m_dataFormats : AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE 2021-03-23 18:31:48.630 T:930 INFO : m_streamTypes : No passthrough capabilities 2021-03-23 18:31:48.630 T:930 INFO : Device 2 2021-03-23 18:31:48.630 T:930 INFO : m_deviceName : sysdefault 2021-03-23 18:31:48.630 T:930 INFO : m_displayName : vc4-hdmi-0 () 2021-03-23 18:31:48.630 T:930 INFO : m_displayNameExtra: MAI PCM vc4-hdmi-hifi-0 2021-03-23 18:31:48.630 T:930 INFO : m_deviceType : AE_DEVTYPE_PCM 2021-03-23 18:31:48.630 T:930 INFO : m_channels : FL, FR 2021-03-23 18:31:48.630 T:930 INFO : m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 2021-03-23 18:31:48.630 T:930 INFO : m_dataFormats : AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE 2021-03-23 18:31:48.630 T:930 INFO : m_streamTypes : No passthrough capabilities 2021-03-23 18:31:48.630 T:930 INFO : Device 3 2021-03-23 18:31:48.630 T:930 INFO : m_deviceName : sysdefault:CARD=vc4hdmi0 2021-03-23 18:31:48.630 T:930 INFO : m_displayName : vc4-hdmi-0 (vc4hdmi0) 2021-03-23 18:31:48.630 T:930 INFO : m_displayNameExtra: MAI PCM vc4-hdmi-hifi-0 2021-03-23 18:31:48.630 T:930 INFO : m_deviceType : AE_DEVTYPE_PCM 2021-03-23 18:31:48.630 T:930 INFO : m_channels : FL, FR 2021-03-23 18:31:48.630 T:930 INFO : m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 2021-03-23 18:31:48.630 T:930 INFO : m_dataFormats : AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE 2021-03-23 18:31:48.630 T:930 INFO : m_streamTypes : No passthrough capabilities 2021-03-23 18:31:48.630 T:930 INFO : Device 4 2021-03-23 18:31:48.631 T:930 INFO : m_deviceName : hdmi:CARD=vc4hdmi0,DEV=0 2021-03-23 18:31:48.631 T:930 INFO : m_displayName : vc4-hdmi-0 (vc4hdmi0) 2021-03-23 18:31:48.631 T:930 INFO : m_displayNameExtra: CTV WD39HB2108 on HDMI 2021-03-23 18:31:48.631 T:930 INFO : m_deviceType : AE_DEVTYPE_HDMI 2021-03-23 18:31:48.631 T:930 INFO : m_channels : FL, FR 2021-03-23 18:31:48.631 T:930 INFO : m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 2021-03-23 18:31:48.631 T:930 INFO : m_dataFormats : AE_FMT_RAW,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE,AE_FMT_U8,AE_FMT_RAW 2021-03-23 18:31:48.631 T:930 INFO : 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 2021-03-23 18:31:48.631 T:930 INFO : Enumerated PULSE devices: 2021-03-23 18:31:48.631 T:930 INFO : Device 1 2021-03-23 18:31:48.631 T:930 INFO : m_deviceName : Default 2021-03-23 18:31:48.631 T:930 INFO : m_displayName : Default 2021-03-23 18:31:48.631 T:930 INFO : m_displayNameExtra: Bluetooth Audio (PULSEAUDIO) 2021-03-23 18:31:48.631 T:930 INFO : m_deviceType : AE_DEVTYPE_PCM 2021-03-23 18:31:48.631 T:930 INFO : m_channels : FL, FR 2021-03-23 18:31:48.631 T:930 INFO : m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000 2021-03-23 18:31:48.631 T:930 INFO : m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT 2021-03-23 18:31:48.631 T:930 INFO : m_streamTypes : No passthrough capabilities 2021-03-23 18:31:48.631 T:931 INFO : CActiveAESink::OpenSink - initialize sink 2021-03-23 18:31:48.631 T:931 INFO : CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=vc4hdmi0,DEV=0" 2021-03-23 18:31:48.635 T:931 INFO : CAESinkALSA::Initialize - Opened device "hdmi:CARD=vc4hdmi0,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 2021-03-23 18:31:48.635 T:931 INFO : CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 2021-03-23 18:31:48.635 T:931 INFO : CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3 2021-03-23 18:31:48.644 T:925 INFO : CKeyboardLayoutManager: loading keyboard layouts from special://xbmc/system/keyboardlayouts... 2021-03-23 18:31:48.789 T:925 INFO : RetroPlayer[PROCESS]: Registering process control for GBM 2021-03-23 18:31:48.789 T:925 INFO : RetroPlayer[RENDER]: Registering renderer factory for DMA 2021-03-23 18:31:48.789 T:925 INFO : RetroPlayer[RENDER]: Registering renderer factory for OpenGLES 2021-03-23 18:31:48.819 T:925 INFO : CDRMUtils::FindConnector - using connector: 32 2021-03-23 18:31:48.852 T:925 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:31:48.852 T:925 INFO : CDRMUtils::FindEncoder - using encoder: 31 2021-03-23 18:31:48.852 T:925 INFO : CDRMUtils::FindPlanes - using crtc: 74 2021-03-23 18:31:49.023 T:925 INFO : Found resolution 1920x1080 with 4096x2160 @ 24.000000 Hz 2021-03-23 18:31:49.023 T:925 INFO : Found resolution 1920x1080 with 4096x2160 @ 23.976025 Hz 2021-03-23 18:31:49.023 T:925 INFO : Found resolution 1920x1080 with 3840x2160 @ 30.000000 Hz 2021-03-23 18:31:49.023 T:925 INFO : Found resolution 1920x1080 with 3840x2160 @ 29.970032 Hz 2021-03-23 18:31:49.023 T:925 INFO : Found resolution 1920x1080 with 3840x2160 @ 25.000000 Hz 2021-03-23 18:31:49.023 T:925 INFO : Found resolution 1920x1080 with 3840x2160 @ 24.000000 Hz 2021-03-23 18:31:49.023 T:925 INFO : Found resolution 1920x1080 with 3840x2160 @ 23.976025 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1920x1080 with 1920x1080 @ 60.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1920x1080 with 1920x1080 @ 59.940063 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1920x1080 with 1920x1080i @ 60.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1920x1080 with 1920x1080i @ 59.940063 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1920x1080 with 1920x1080 @ 50.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1920x1080 with 1920x1080i @ 50.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1920x1080 with 1920x1080 @ 30.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1920x1080 with 1920x1080 @ 29.970032 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1920x1080 with 1920x1080 @ 25.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1920x1080 with 1920x1080 @ 24.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1920x1080 with 1920x1080 @ 23.976025 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1280x1024 with 1280x1024 @ 75.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1280x1024 with 1280x1024 @ 60.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1440x900 with 1440x900 @ 60.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1280x960 with 1280x960 @ 60.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1280x800 with 1280x800 @ 60.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1152x864 with 1152x864 @ 75.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1280x768 with 1280x768 @ 60.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1280x720 with 1280x720 @ 60.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1280x720 with 1280x720 @ 59.940063 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1280x720 with 1280x720 @ 50.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1440x576 with 1440x576 @ 50.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1024x768 with 1024x768 @ 75.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1024x768 with 1024x768 @ 70.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 1024x768 with 1024x768 @ 60.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 800x600 with 800x600 @ 75.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 800x600 with 800x600 @ 72.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 800x600 with 800x600 @ 60.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 720x576 with 720x576 @ 50.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 720x480 with 720x480 @ 59.940063 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 720x480 with 720x480 @ 60.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 640x480 with 640x480 @ 75.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 640x480 with 640x480 @ 73.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 640x480 with 640x480 @ 60.000000 Hz 2021-03-23 18:31:49.024 T:925 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:31:49.024 T:925 INFO : Found resolution 720x400 with 720x400 @ 70.000000 Hz 2021-03-23 18:31:49.042 T:925 INFO : EGL_VERSION = 1.4 2021-03-23 18:31:49.042 T:925 INFO : EGL_VENDOR = Mesa Project 2021-03-23 18:31:49.042 T:925 INFO : EGL_EXTENSIONS = EGL_ANDROID_blob_cache EGL_EXT_buffer_age EGL_EXT_image_dma_buf_import EGL_EXT_image_dma_buf_import_modifiers EGL_KHR_cl_event2 EGL_KHR_config_attribs EGL_KHR_create_context EGL_KHR_create_context_no_error EGL_KHR_fence_sync EGL_KHR_get_all_proc_addresses EGL_KHR_gl_colorspace EGL_KHR_gl_renderbuffer_image EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_3D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_KHR_no_config_context EGL_KHR_reusable_sync EGL_KHR_surfaceless_context EGL_EXT_pixel_format_float EGL_KHR_wait_sync EGL_MESA_configless_context EGL_MESA_drm_image EGL_MESA_image_dma_buf_export EGL_MESA_query_driver 2021-03-23 18:31:49.042 T:925 INFO : EGL_CLIENT_EXTENSIONS = EGL_EXT_client_extensions EGL_EXT_device_base EGL_EXT_device_enumeration EGL_EXT_device_query EGL_EXT_platform_base EGL_KHR_client_get_all_proc_addresses EGL_KHR_debug EGL_EXT_platform_device EGL_MESA_platform_gbm EGL_KHR_platform_gbm EGL_MESA_platform_surfaceless 2021-03-23 18:31:49.063 T:925 INFO : CApplication::CreateGUI - using the gbm windowing system 2021-03-23 18:31:49.063 T:925 INFO : Checking resolution 16 2021-03-23 18:31:49.077 T:925 INFO : GL_VENDOR = Broadcom 2021-03-23 18:31:49.077 T:925 INFO : GL_RENDERER = V3D 4.2 2021-03-23 18:31:49.077 T:925 INFO : GL_VERSION = OpenGL ES 3.1 Mesa 20.3.4 2021-03-23 18:31:49.077 T:925 INFO : GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 3.10 2021-03-23 18:31:49.077 T:925 INFO : GL_EXTENSIONS = GL_EXT_blend_minmax GL_EXT_multi_draw_arrays GL_EXT_texture_compression_s3tc GL_EXT_texture_compression_dxt1 GL_EXT_texture_format_BGRA8888 GL_OES_compressed_ETC1_RGB8_texture GL_OES_depth24 GL_OES_element_index_uint GL_OES_fbo_render_mipmap GL_OES_mapbuffer GL_OES_rgb8_rgba8 GL_OES_standard_derivatives GL_OES_stencil8 GL_OES_texture_3D GL_OES_texture_float GL_OES_texture_half_float GL_OES_texture_half_float_linear GL_OES_texture_npot GL_OES_vertex_half_float GL_EXT_draw_instanced GL_EXT_texture_sRGB_decode GL_OES_EGL_image GL_OES_depth_texture GL_OES_packed_depth_stencil GL_EXT_texture_type_2_10_10_10_REV GL_OES_get_program_binary GL_APPLE_texture_max_level GL_EXT_discard_framebuffer GL_EXT_read_format_bgra GL_EXT_frag_depth GL_NV_fbo_color_attachments GL_OES_EGL_image_external GL_OES_EGL_sync GL_OES_vertex_array_object GL_ANGLE_pack_reverse_row_order GL_ANGLE_texture_compression_dxt3 GL_ANGLE_texture_compression_dxt5 GL_EXT_occlusion_query_boolean GL_EXT_texture_rg GL_EXT_unpack_subimage GL_NV_draw_buffers GL_NV_read_buffer GL_NV_read_depth GL_NV_read_depth_stencil GL_NV_read_stencil GL_EXT_draw_buffers GL_EXT_map_buffer_range GL_KHR_debug GL_KHR_texture_compression_astc_ldr GL_NV_pixel_buffer_object GL_OES_depth_texture_cube_map GL_OES_required_internalformat GL_OES_surfaceless_context GL_EXT_color_buffer_float GL_EXT_sRGB_write_control GL_EXT_separate_shader_objects GL_EXT_shader_implicit_conversions GL_EXT_shader_integer_mix GL_EXT_base_instance GL_EXT_compressed_ETC1_RGB8_sub_texture GL_EXT_draw_elements_base_vertex GL_EXT_primitive_bounding_box GL_EXT_shader_io_blocks GL_EXT_texture_border_clamp GL_KHR_context_flush_control GL_NV_image_formats GL_OES_draw_elements_base_vertex GL_OES_primitive_bounding_box GL_OES_shader_io_blocks GL_OES_texture_border_clamp GL_OES_texture_stencil8 GL_OES_texture_storage_multisample_2d_array GL_EXT_buffer_storage GL_EXT_float_blend GL_EXT_geometry_point_size GL_EXT_geometry_shader GL_KHR_no_error GL_KHR_texture_compression_astc_sliced_3d GL_OES_EGL_image_external_essl3 GL_OES_geometry_point_size GL_OES_geometry_shader GL_OES_shader_image_atomic GL_EXT_texture_compression_s3tc_srgb GL_MESA_shader_integer_functions GL_KHR_parallel_shader_compile GL_EXT_EGL_image_storage GL_MESA_framebuffer_flip_y GL_EXT_texture_query_lod 2021-03-23 18:31:49.148 T:925 INFO : GLES: Maximum texture width: 4096 2021-03-23 18:31:49.257 T:929 INFO : Loading special://xbmc/system/Lircmap.xml 2021-03-23 18:31:49.273 T:929 INFO : * Adding remote mapping for device 'mceusb' 2021-03-23 18:31:49.273 T:929 INFO : * Adding remote mapping for device 'XboxDVDDongle' 2021-03-23 18:31:49.273 T:929 INFO : * Adding remote mapping for device 'Microsoft_Xbox' 2021-03-23 18:31:49.273 T:929 INFO : * Adding remote mapping for device 'PinnacleSysPCTVRemote' 2021-03-23 18:31:49.273 T:929 INFO : * Adding remote mapping for device 'anysee' 2021-03-23 18:31:49.273 T:929 INFO : * Adding remote mapping for device 'iMON-PAD' 2021-03-23 18:31:49.273 T:929 INFO : * Adding remote mapping for device 'Antec_Veris_RM200' 2021-03-23 18:31:49.274 T:929 INFO : * Adding remote mapping for device 'MCE_via_iMON' 2021-03-23 18:31:49.274 T:929 INFO : * Adding remote mapping for device 'TwinHanRemote' 2021-03-23 18:31:49.274 T:929 INFO : * Adding remote mapping for device 'linux-input-layer' 2021-03-23 18:31:49.274 T:929 INFO : * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote' 2021-03-23 18:31:49.274 T:929 INFO : * Adding remote mapping for device 'mediacenter' 2021-03-23 18:31:49.274 T:929 INFO : * Adding remote mapping for device 'devinput' 2021-03-23 18:31:49.274 T:929 INFO : * Adding remote mapping for device 'devinput-32' 2021-03-23 18:31:49.274 T:929 INFO : * Adding remote mapping for device 'devinput-64' 2021-03-23 18:31:49.774 T:925 INFO : load keymapping 2021-03-23 18:31:49.777 T:925 INFO : Loading special://xbmc/system/keymaps/appcommand.xml 2021-03-23 18:31:49.777 T:925 INFO : Loading special://xbmc/system/keymaps/customcontroller.AppleRemote.xml 2021-03-23 18:31:49.778 T:925 INFO : Loading special://xbmc/system/keymaps/customcontroller.Harmony.xml 2021-03-23 18:31:49.781 T:925 INFO : Loading special://xbmc/system/keymaps/customcontroller.SiriRemote.xml 2021-03-23 18:31:49.782 T:925 INFO : Loading special://xbmc/system/keymaps/gamepad.xml 2021-03-23 18:31:49.784 T:925 INFO : Loading special://xbmc/system/keymaps/joystick.xml 2021-03-23 18:31:49.787 T:925 INFO : Loading special://xbmc/system/keymaps/keyboard.xml 2021-03-23 18:31:49.793 T:925 INFO : Loading special://xbmc/system/keymaps/mouse.xml 2021-03-23 18:31:49.794 T:925 INFO : Loading special://xbmc/system/keymaps/remote.xml 2021-03-23 18:31:49.798 T:925 INFO : Loading special://xbmc/system/keymaps/touchscreen.xml 2021-03-23 18:31:49.801 T:925 INFO : GUI format 1920x1080, Display 4096x2160 @ 24.000000 Hz 2021-03-23 18:31:49.801 T:925 INFO : CLangInfo: loading resource.language.en_gb language information... 2021-03-23 18:31:49.807 T:925 INFO : global locale set to C 2021-03-23 18:31:49.807 T:925 INFO : CLangInfo: loading resource.language.en_gb language strings... 2021-03-23 18:31:49.883 T:936 INFO : Running database version Addons33 2021-03-23 18:31:49.885 T:936 INFO : Running database version ViewModes6 2021-03-23 18:31:49.888 T:936 INFO : Running database version Textures13 2021-03-23 18:31:49.954 T:936 INFO : MYSQL: Connected to version 10.3.27-MariaDB-0+deb10u1 2021-03-23 18:31:49.978 T:936 INFO : Running database version MyMusic82 2021-03-23 18:31:50.000 T:936 INFO : Running database version MyVideos119 2021-03-23 18:31:50.004 T:936 INFO : Running database version TV37 2021-03-23 18:31:50.273 T:936 INFO : Running database version Epg13 2021-03-23 18:31:50.315 T:925 INFO : start dvd mediatype detection 2021-03-23 18:31:50.357 T:925 INFO : Unloaded skin 2021-03-23 18:31:50.359 T:925 INFO : load skin from: /storage/.kodi/addons/skin.confluence/ (version: 4.7.6) 2021-03-23 18:31:50.359 T:925 INFO : load fonts for skin... 2021-03-23 18:31:50.372 T:925 INFO : Loading skin includes from /storage/.kodi/addons/skin.confluence/720p/Includes.xml 2021-03-23 18:31:50.494 T:925 INFO : Loading fonts from /storage/.kodi/addons/skin.confluence/720p/Font.xml 2021-03-23 18:31:50.545 T:925 INFO : load new skin... 2021-03-23 18:31:50.546 T:925 INFO : Loading custom window XMLs from skin path /storage/.kodi/addons/skin.confluence/720p 2021-03-23 18:31:50.552 T:925 INFO : initialize new skin... 2021-03-23 18:31:50.552 T:925 INFO : Loading skin file: custom_PVRChannelNumberInput.xml, load type: LOAD_ON_GUI_INIT 2021-03-23 18:31:50.553 T:925 INFO : Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT 2021-03-23 18:31:50.566 T:925 INFO : Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT 2021-03-23 18:31:50.568 T:925 INFO : Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT 2021-03-23 18:31:50.571 T:925 INFO : Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT 2021-03-23 18:31:50.572 T:925 INFO : Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT 2021-03-23 18:31:50.583 T:925 INFO : Loading skin file: DialogNotification.xml, load type: LOAD_ON_GUI_INIT 2021-03-23 18:31:50.584 T:925 INFO : Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT 2021-03-23 18:31:50.586 T:925 INFO : Loading resource://resource.uisounds.kodi/sounds.xml 2021-03-23 18:31:50.658 T:925 INFO : skin loaded... 2021-03-23 18:31:50.658 T:925 INFO : Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME 2021-03-23 18:31:50.659 T:925 INFO : Loading skin file: Home.xml, load type: KEEP_IN_MEMORY 2021-03-23 18:31:51.265 T:925 WARNING : JSONRPC: Could not parse type "Setting.Details.SettingList" 2021-03-23 18:31:51.266 T:925 INFO : JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting") 2021-03-23 18:31:51.266 T:925 INFO : JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting 2021-03-23 18:31:51.299 T:925 INFO : JSONRPC v12.2.1: Successfully initialized 2021-03-23 18:31:51.339 T:925 INFO : AddOnLog: peripheral.joystick: Enabling joystick interface "linux" 2021-03-23 18:31:51.341 T:925 INFO : Register - new keyboard device registered on application->keyboard: Keyboard (0000:0000) 2021-03-23 18:31:51.342 T:925 INFO : Register - new mouse device registered on application->mouse: Mouse (0000:0000) 2021-03-23 18:31:51.342 T:925 INFO : Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 2021-03-23 18:31:51.352 T:925 INFO : Loaded playercorefactory configuration 2021-03-23 18:31:51.352 T:925 INFO : Loading player core factory settings from special://masterprofile/playercorefactory.xml. 2021-03-23 18:31:51.352 T:925 INFO : special://masterprofile/playercorefactory.xml does not exist. Skipping. 2021-03-23 18:31:51.352 T:925 INFO : removing tempfiles 2021-03-23 18:31:51.357 T:925 INFO : initialize done 2021-03-23 18:31:51.357 T:981 INFO : initializing python engine. 2021-03-23 18:31:51.357 T:925 INFO : Running the application... 2021-03-23 18:31:51.363 T:925 INFO : starting zeroconf publishing 2021-03-23 18:31:51.364 T:983 INFO : ES: Starting UDP Event server on port 9777 2021-03-23 18:31:51.364 T:983 INFO : UDP: Listening on port 9777 (ipv6 : false) 2021-03-23 18:31:51.383 T:925 INFO : JSONRPC Server: Successfully initialized 2021-03-23 18:31:51.399 T:977 INFO : Register - new cec device registered on cec->Linux: CEC Adapter (0000:0000) 2021-03-23 18:31:51.748 T:974 INFO : AddOnLog: pvr.nextpvr: Creating NextPVR PVR-Client 2021-03-23 18:31:51.755 T:974 INFO : AddOnLog: pvr.nextpvr: Buffer created! 2021-03-23 18:31:51.755 T:974 INFO : AddOnLog: pvr.nextpvr: DummyBuffer created! 2021-03-23 18:31:51.755 T:974 INFO : AddOnLog: pvr.nextpvr: Buffer created! 2021-03-23 18:31:51.755 T:974 INFO : AddOnLog: pvr.nextpvr: RecordingBuffer created! 2021-03-23 18:31:51.755 T:974 INFO : AddOnLog: pvr.nextpvr: Buffer created! 2021-03-23 18:31:51.755 T:974 INFO : AddOnLog: pvr.nextpvr: DummyBuffer created! 2021-03-23 18:31:51.796 T:974 INFO : AddOnLog: pvr.nextpvr: NextPVR version: 50100 2021-03-23 18:31:51.796 T:974 INFO : AddOnLog: pvr.nextpvr: Server time offset in seconds: 0 2021-03-23 18:31:51.796 T:974 INFO : AddOnLog: pvr.nextpvr: time shift buffer in seconds: 1200 2021-03-23 18:31:51.836 T:974 INFO : PVR Manager: Starting 2021-03-23 18:31:52.200 T:994 INFO : PVR Manager: Started 2021-03-23 18:31:52.949 T:981 INFO : SETTINGS: set_keyboard_layout # us 2021-03-23 18:31:52.949 T:981 INFO : SETTINGS: set_keyboard_layout # loadkmap < `ls -1 /usr/lib/keymaps/*/us.bmap` 2021-03-23 18:31:54.196 T:981 INFO : ## LibreELEC Addon ## updates::updateThread ## Started 2021-03-23 18:31:54.198 T:981 INFO : ## LibreELEC Addon ## updates::set_auto_update ## auto 2021-03-23 18:31:54.308 T:1057 INFO : SETTINGS: run # Waiting 2021-03-23 18:32:06.668 T:993 INFO : CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 2021-03-23 18:32:15.440 T:925 INFO : Loading skin file: MyPVRChannels.xml, load type: KEEP_IN_MEMORY 2021-03-23 18:32:20.485 T:992 INFO : CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 2021-03-23 18:32:23.190 T:925 INFO : Loading skin file: Settings.xml, load type: KEEP_IN_MEMORY 2021-03-23 18:32:24.648 T:925 INFO : Loading skin file: SettingsCategory.xml, load type: KEEP_IN_MEMORY 2021-03-23 18:32:46.986 T:925 INFO : Log level changed to "TRACE" 2021-03-23 18:32:46.986 T:925 INFO : Enabled debug logging due to GUI setting. Level 2. 2021-03-23 18:32:46.987 T:925 DEBUG : ------ Window Init () ------ 2021-03-23 18:32:47.444 T:985 DEBUG : PushCecKeypress - received key b duration 468 2021-03-23 18:32:48.781 T:985 DEBUG : PushCecKeypress - received key d8 duration 0 2021-03-23 18:32:48.789 T:925 DEBUG : HandleKey: menu (0xd8) pressed, action is PreviousMenu 2021-03-23 18:32:48.813 T:925 DEBUG : CGUIWindowManager::PreviousWindow: Deactivate 2021-03-23 18:32:49.029 T:925 DEBUG : ------ Window Deinit (SettingsCategory.xml) ------ 2021-03-23 18:32:49.030 T:925 DEBUG : FreeVisualisation() done 2021-03-23 18:32:49.065 T:925 DEBUG : CGUIWindowManager::PreviousWindow: Activate new 2021-03-23 18:32:49.065 T:925 DEBUG : ------ Window Init (Settings.xml) ------ 2021-03-23 18:32:49.074 T:974 DEBUG : ffmpeg[0x1ab1be0X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format. 2021-03-23 18:32:49.175 T:974 DEBUG : DoWork - took 107 ms to load special://skin/backgrounds/settings.jpg 2021-03-23 18:32:49.240 T:985 DEBUG : PushCecKeypress - received key d8 duration 459 2021-03-23 18:32:49.814 T:985 DEBUG : PushCecKeypress - received key d8 duration 0 2021-03-23 18:32:49.815 T:925 DEBUG : HandleKey: menu (0xd8) pressed, action is PreviousMenu 2021-03-23 18:32:49.815 T:925 DEBUG : CGUIWindowManager::PreviousWindow: Deactivate 2021-03-23 18:32:50.069 T:925 DEBUG : ------ Window Deinit (Settings.xml) ------ 2021-03-23 18:32:50.069 T:925 DEBUG : FreeVisualisation() done 2021-03-23 18:32:50.106 T:925 DEBUG : CGUIWindowManager::PreviousWindow: Activate new 2021-03-23 18:32:50.106 T:925 DEBUG : ------ Window Init (Home.xml) ------ 2021-03-23 18:32:50.115 T:936 DEBUG : CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update 2021-03-23 18:32:50.115 T:936 DEBUG : connect replacing configured host mysql-pi01 with resolved host 192.168.1.177 2021-03-23 18:32:50.198 T:936 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:32:50.198 T:936 DEBUG : GetRecentlyAddedAlbumSongs() query: SELECT songview.*, songartistview.* FROM (SELECT idAlbum, dateAdded FROM album ORDER BY dateAdded DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum = recentalbums.idAlbum JOIN songartistview ON songview.idSong = songartistview.idSong ORDER BY recentalbums.dateAdded DESC, songview.idAlbum DESC, songview.idSong, songartistview.idRole, songartistview.iOrder 2021-03-23 18:32:50.235 T:985 DEBUG : PushCecKeypress - received key d8 duration 422 2021-03-23 18:32:50.439 T:936 DEBUG : GetRecentlyAddedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM album WHERE strAlbum != '' ORDER BY dateAdded DESC LIMIT 10) AS recentalbums JOIN albumview ON albumview.idAlbum = recentalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY dateAdded DESC, albumview.idAlbum desc, albumartistview.iOrder 2021-03-23 18:32:50.472 T:936 DEBUG : CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update 2021-03-23 18:32:50.472 T:936 DEBUG : connect replacing configured host mysql-pi01 with resolved host 192.168.1.177 2021-03-23 18:32:50.612 T:974 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:32:50.612 T:974 DEBUG : DoWork - trying to extract thumb from video file smb://nas01/Public/Shared Videos/Ride Along/Ride Along.mkv 2021-03-23 18:32:50.620 T:974 DEBUG : CSMBFile::Open - opened smb://nas01/Public/Shared Videos/Ride Along/Ride Along.mkv, fd=10000 2021-03-23 18:32:50.722 T:974 DEBUG : Open - probing detected format [matroska,webm] 2021-03-23 18:32:50.941 T:974 DEBUG : Open - avformat_find_stream_info starting 2021-03-23 18:32:50.983 T:974 DEBUG : Open - av_find_stream_info finished 2021-03-23 18:32:50.983 T:974 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 0 2021-03-23 18:32:50.983 T:974 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 1 2021-03-23 18:32:50.983 T:974 DEBUG : ScanForExternalSubtitles: Searching for subtitles... 2021-03-23 18:32:51.041 T:974 DEBUG : ScanForExternalSubtitles: END (total time: 57 ms) 2021-03-23 18:32:51.041 T:974 INFO : CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 2021-03-23 18:32:51.041 T:974 DEBUG : CDVDVideoCodecFFmpeg - Updated codec: ff-h264 2021-03-23 18:32:51.041 T:974 DEBUG : ExtractThumb - seeking to pos 1993091ms (total: 5979273ms) in smb://nas01/Public/Shared Videos/Ride Along/Ride Along.mkv 2021-03-23 18:32:51.085 T:936 DEBUG : CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update 2021-03-23 18:32:51.085 T:936 DEBUG : connect replacing configured host mysql-pi01 with resolved host 192.168.1.177 2021-03-23 18:32:51.199 T:974 DEBUG : SeekTime - seek ended up on time 1993000 2021-03-23 18:32:51.248 T:985 DEBUG : PushCecKeypress - received key a8 duration 0 2021-03-23 18:32:51.263 T:936 DEBUG : connect replacing configured host mysql-pi01 with resolved host 192.168.1.177 2021-03-23 18:32:51.273 T:925 DEBUG : HandleKey: 168 (0xa8, obc87) pressed, action is Right 2021-03-23 18:32:51.412 T:974 DEBUG : cached image 'special://masterprofile/Thumbnails/c/c9f59e0b.jpg' size 540x303 2021-03-23 18:32:51.435 T:974 DEBUG : ExtractThumb - measured 822 ms to extract thumb from file in 6 packets. 2021-03-23 18:32:51.435 T:974 DEBUG : CSMBFile::Close closing fd 10000 2021-03-23 18:32:51.502 T:974 DEBUG : connect replacing configured host mysql-pi01 with resolved host 192.168.1.177 2021-03-23 18:32:51.531 T:985 DEBUG : PushCecKeypress - received key a8 duration 283 2021-03-23 18:32:51.532 T:974 DEBUG : connect replacing configured host mysql-pi01 with resolved host 192.168.1.177 2021-03-23 18:32:51.564 T:974 DEBUG : Mysql Start transaction 2021-03-23 18:32:51.564 T:974 DEBUG : Mysql execute: DELETE FROM streamdetails WHERE idFile = 1361 2021-03-23 18:32:51.566 T:974 DEBUG : Mysql execute: INSERT INTO streamdetails (idFile, iStreamType, strVideoCodec, fVideoAspect, iVideoWidth, iVideoHeight, iVideoDuration, strStereoMode, strVideoLanguage) VALUES (1361,0,'h264',1.777778,1280,720,5979,'','') 2021-03-23 18:32:51.568 T:974 DEBUG : Mysql execute: INSERT INTO streamdetails (idFile, iStreamType, strAudioCodec, iAudioChannels, strAudioLanguage) VALUES (1361,1,'aac',6,'') 2021-03-23 18:32:51.570 T:974 DEBUG : Mysql execute: update movie set c11=5979 where idFile=1361 and c11='' 2021-03-23 18:32:51.571 T:974 DEBUG : Mysql execute: update episode set c09=5979 where idFile=1361 and c09='' 2021-03-23 18:32:51.573 T:974 DEBUG : Mysql execute: update musicvideo set c04=5979 where idFile=1361 and c04='' 2021-03-23 18:32:51.590 T:974 DEBUG : Mysql commit transaction 2021-03-23 18:32:52.342 T:985 DEBUG : PushCecKeypress - received key a7 duration 0 2021-03-23 18:32:52.357 T:925 DEBUG : HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-03-23 18:32:52.626 T:985 DEBUG : PushCecKeypress - received key a7 duration 284 2021-03-23 18:32:52.944 T:985 DEBUG : PushCecKeypress - received key a8 duration 0 2021-03-23 18:32:52.955 T:925 DEBUG : HandleKey: 168 (0xa8, obc87) pressed, action is Right 2021-03-23 18:32:53.220 T:985 DEBUG : PushCecKeypress - received key a8 duration 276 2021-03-23 18:32:53.679 T:985 DEBUG : PushCecKeypress - received key b duration 0 2021-03-23 18:32:53.681 T:925 DEBUG : HandleKey: 11 (0x0b, obc244) pressed, action is Select 2021-03-23 18:32:53.681 T:925 DEBUG : Activating window ID: 10702 2021-03-23 18:32:53.945 T:925 DEBUG : ------ Window Deinit (Home.xml) ------ 2021-03-23 18:32:53.946 T:925 DEBUG : FreeVisualisation() done 2021-03-23 18:32:53.981 T:925 DEBUG : ------ Window Init (MyPVRGuide.xml) ------ 2021-03-23 18:32:53.981 T:925 INFO : Loading skin file: MyPVRGuide.xml, load type: KEEP_IN_MEMORY 2021-03-23 18:32:54.042 T:1069 DEBUG : Thread epg-grid-refresh-timeline-items start, auto delete: false 2021-03-23 18:32:54.115 T:985 DEBUG : PushCecKeypress - received key b duration 436 2021-03-23 18:32:54.258 T:991 DEBUG : CurlFile::ParseAndCorrectUrl() adding custom header option 'Accept-Encoding: identity' 2021-03-23 18:32:54.258 T:991 DEBUG : CurlFile::Open(0xad817620) http://192.168.1.189:8866/service?method=recording.lastupdated&sid=9120575bebe94fcd97b658aa3600b03a 2021-03-23 18:32:54.259 T:991 DEBUG : easy_acquire - Created session to http://192.168.1.189 2021-03-23 18:32:54.275 T:991 DEBUG : AddOnLog: pvr.nextpvr: DoMethodRequest recording.lastupdated 0 107 16 2021-03-23 18:32:55.494 T:985 DEBUG : PushCecKeypress - received key a7 duration 0 2021-03-23 18:32:55.523 T:925 DEBUG : HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-03-23 18:32:55.711 T:985 DEBUG : PushCecKeypress - received key a7 duration 218 2021-03-23 18:32:56.676 T:985 DEBUG : PushCecKeypress - received key b duration 0 2021-03-23 18:32:56.690 T:925 DEBUG : HandleKey: 11 (0x0b, obc244) pressed, action is Select 2021-03-23 18:32:56.732 T:925 DEBUG : CPlayerCoreFactory::GetPlayers(pvr://channels/tv/All%20channels/pvr.nextpvr_10220.pvr) 2021-03-23 18:32:56.732 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: system rules 2021-03-23 18:32:56.732 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: matches rule: system rules 2021-03-23 18:32:56.732 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 2021-03-23 18:32:56.732 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 2021-03-23 18:32:56.732 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: rtmp 2021-03-23 18:32:56.732 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: rtsp 2021-03-23 18:32:56.732 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: streams 2021-03-23 18:32:56.732 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: dvd 2021-03-23 18:32:56.732 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: discimage 2021-03-23 18:32:56.732 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 2021-03-23 18:32:56.732 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: nsv 2021-03-23 18:32:56.733 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: radio 2021-03-23 18:32:56.733 T:925 DEBUG : CPlayerCoreFactory::GetPlayers: matched 0 rules with players 2021-03-23 18:32:56.733 T:925 DEBUG : CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 2021-03-23 18:32:56.733 T:925 DEBUG : CPlayerCoreFactory::GetPlayers: for video=1, audio=0 2021-03-23 18:32:56.733 T:925 DEBUG : CPlayerCoreFactory::GetPlayers: for video=1, audio=1 2021-03-23 18:32:56.733 T:925 DEBUG : CPlayerCoreFactory::GetPlayers: added 1 players 2021-03-23 18:32:56.741 T:925 DEBUG : Radio UECP (RDS) Processor - new CDVDRadioRDSData 2021-03-23 18:32:56.741 T:925 INFO : VideoPlayer::OpenFile: pvr://channels/tv/All%20channels/pvr.nextpvr_10220.pvr 2021-03-23 18:32:56.741 T:1070 DEBUG : Thread VideoPlayer start, auto delete: false 2021-03-23 18:32:56.741 T:925 DEBUG : OnPlayBackStarted: CApplication::OnPlayBackStarted 2021-03-23 18:32:56.742 T:1070 INFO : Creating InputStream 2021-03-23 18:32:56.742 T:936 DEBUG : connect replacing configured host mysql-pi01 with resolved host 192.168.1.177 2021-03-23 18:32:56.744 T:1070 DEBUG : AddOnLog: pvr.nextpvr: CloseLiveStream 2021-03-23 18:32:56.745 T:1070 ERROR : AddOnLog: pvr.nextpvr: Unknown live streaming state 0 0 1 2021-03-23 18:32:56.745 T:1070 INFO : AddOnLog: pvr.nextpvr: Calling Open(http://192.168.1.189:8866/live?channeloid=10220&client=XBMC-9120575bebe94fcd97b658aa3600b03a) on tsb! 2021-03-23 18:32:56.746 T:1070 DEBUG : AddOnLog: pvr.nextpvr: Buffer::Open() called! [ http://192.168.1.189:8866/live?channeloid=10220&client=XBMC-9120575bebe94fcd97b658aa3600b03a ] 2021-03-23 18:32:56.748 T:1070 DEBUG : CurlFile::Open(0xb2205460) http://192.168.1.189:8866/live?channeloid=10220&client=XBMC-9120575bebe94fcd97b658aa3600b03a 2021-03-23 18:32:56.768 T:925 DEBUG : CPlayerGUIInfo::InitCurrentItem(pvr://channels/tv/All%20channels/pvr.nextpvr_10220.pvr) 2021-03-23 18:32:56.778 T:936 DEBUG : Loading settings for pvr://channels/tv/All%20channels/pvr.nextpvr_10220.pvr 2021-03-23 18:32:56.815 T:925 DEBUG : CPlayerGUIInfo::InitCurrentItem(pvr://channels/tv/All%20channels/pvr.nextpvr_10220.pvr) 2021-03-23 18:32:57.106 T:985 DEBUG : PushCecKeypress - received key b duration 430 2021-03-23 18:32:57.485 T:1070 DEBUG : CInputStreamPVRChannel - OpenPVRStream - opened channel stream pvr://channels/tv/All%20channels/pvr.nextpvr_10220.pvr 2021-03-23 18:32:57.485 T:1070 INFO : Creating Demuxer 2021-03-23 18:32:57.639 T:1070 DEBUG : Open - probing detected format [mpegts] 2021-03-23 18:32:57.640 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 0 2021-03-23 18:32:57.640 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 1 2021-03-23 18:32:57.641 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 2 2021-03-23 18:32:57.641 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 3 2021-03-23 18:32:57.641 T:1070 DEBUG : AddOnLog: pvr.nextpvr: seek length(0) 2021-03-23 18:32:57.641 T:1070 INFO : Opening stream: 0 source: 256 2021-03-23 18:32:57.641 T:1070 WARNING : OpenStream - Unsupported stream 0. Stream disabled. 2021-03-23 18:32:57.641 T:1070 INFO : Opening stream: 1 source: 256 2021-03-23 18:32:57.641 T:1070 INFO : Finding audio codec for: 86019 2021-03-23 18:32:57.643 T:1070 INFO : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder ac3 2021-03-23 18:32:57.643 T:1070 INFO : Creating audio thread 2021-03-23 18:32:57.643 T:1071 DEBUG : Thread VideoPlayerAudio start, auto delete: false 2021-03-23 18:32:57.643 T:1071 INFO : running thread: CVideoPlayerAudio::Process() 2021-03-23 18:32:57.643 T:1071 DEBUG : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 0 2021-03-23 18:32:57.643 T:1070 DEBUG : CVideoPlayer::SetCaching - caching state 2 2021-03-23 18:32:57.643 T:1070 DEBUG : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2021-03-23 18:32:57.644 T:1070 DEBUG : CVideoPlayer::HandleMessages - player 1 reported state: 0 2021-03-23 18:32:57.644 T:974 DEBUG : OnAVChange: CApplication::OnAVChange 2021-03-23 18:32:57.644 T:1070 DEBUG : CDVDClock::SetSpeedAdjust - adjusted:-0.050000 2021-03-23 18:32:57.650 T:925 DEBUG : FreeVisualisation() done 2021-03-23 18:32:57.653 T:1071 DEBUG : CDVDAudio::Pause - pausing audio stream 2021-03-23 18:32:57.763 T:1070 ERROR : ffmpeg[0x1b9ef30X]: [NULL] non-existing PPS 0 referenced 2021-03-23 18:32:57.764 T:1070 INFO : CDVDDemuxFFmpeg::Read() stream change 2021-03-23 18:32:57.764 T:1070 INFO : ffmpeg[0x1b9ef30X]: Input #0, mpegts, from 'pvr://channels/tv/All%20channels/pvr.nextpvr_10220.pvr': 2021-03-23 18:32:57.764 T:1070 INFO : ffmpeg[0x1b9ef30X]: Duration: N/A, bitrate: N/A 2021-03-23 18:32:57.764 T:1070 INFO : ffmpeg[0x1b9ef30X]: Program 1 2021-03-23 18:32:57.764 T:1070 INFO : ffmpeg[0x1b9ef30X]: Stream #0:0[0x1439]: Video: h264 ([27][0][0][0] / 0x001B), none, 90k tbn 2021-03-23 18:32:57.764 T:1070 INFO : ffmpeg[0x1b9ef30X]: Stream #0:1[0x143a](eng): Audio: ac3 (AC-3 / 0x332D4341), 0 channels 2021-03-23 18:32:57.764 T:1070 INFO : ffmpeg[0x1b9ef30X]: Stream #0:2[0x143b](spa): Audio: ac3 (AC-3 / 0x332D4341), 0 channels 2021-03-23 18:32:57.764 T:1070 INFO : ffmpeg[0x1b9ef30X]: Stream #0:3[0x143c]: Data: scte_35 2021-03-23 18:32:57.765 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 0 2021-03-23 18:32:57.765 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 1 2021-03-23 18:32:57.765 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 2 2021-03-23 18:32:57.765 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 3 2021-03-23 18:32:57.765 T:1070 INFO : Opening stream: 0 source: 256 2021-03-23 18:32:57.765 T:1070 WARNING : OpenStream - Unsupported stream 0. Stream disabled. 2021-03-23 18:32:57.765 T:1070 INFO : Opening stream: 1 source: 256 2021-03-23 18:32:57.767 T:1070 ERROR : ffmpeg[0x1b9ef30X]: [NULL] non-existing PPS 0 referenced 2021-03-23 18:32:57.796 T:1070 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:32:57.796 T:1070 DEBUG : CVideoPlayer::HandleMessages - player 1 reported state: 0 2021-03-23 18:32:57.796 T:1070 ERROR : ffmpeg[0x1b9ef30X]: [NULL] non-existing PPS 0 referenced 2021-03-23 18:32:57.962 T:1070 INFO : Skipped 10 duplicate messages.. 2021-03-23 18:32:57.962 T:1070 INFO : CDVDDemuxFFmpeg::Read() stream change 2021-03-23 18:32:57.962 T:1070 INFO : ffmpeg[0x1b9ef30X]: Input #0, mpegts, from 'pvr://channels/tv/All%20channels/pvr.nextpvr_10220.pvr': 2021-03-23 18:32:57.962 T:1070 INFO : ffmpeg[0x1b9ef30X]: Duration: N/A, bitrate: N/A 2021-03-23 18:32:57.962 T:1070 INFO : ffmpeg[0x1b9ef30X]: Program 1 2021-03-23 18:32:57.962 T:1070 INFO : ffmpeg[0x1b9ef30X]: Stream #0:0[0x1439]: Video: h264 ([27][0][0][0] / 0x001B), none, 90k tbn 2021-03-23 18:32:57.962 T:1070 INFO : ffmpeg[0x1b9ef30X]: Stream #0:1[0x143a](eng): Audio: ac3 (AC-3 / 0x332D4341), 0 channels 2021-03-23 18:32:57.962 T:1070 INFO : ffmpeg[0x1b9ef30X]: Stream #0:2[0x143b](spa): Audio: ac3 (AC-3 / 0x332D4341), 48000 Hz, stereo, 192 kb/s 2021-03-23 18:32:57.962 T:1070 INFO : ffmpeg[0x1b9ef30X]: Stream #0:3[0x143c]: Data: scte_35 2021-03-23 18:32:57.963 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 0 2021-03-23 18:32:57.963 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 1 2021-03-23 18:32:57.963 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 2 2021-03-23 18:32:57.963 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 3 2021-03-23 18:32:57.963 T:1070 INFO : Opening stream: 0 source: 256 2021-03-23 18:32:57.963 T:1070 WARNING : OpenStream - Unsupported stream 0. Stream disabled. 2021-03-23 18:32:57.963 T:1070 INFO : Opening stream: 2 source: 256 2021-03-23 18:32:57.963 T:1070 INFO : Finding audio codec for: 86019 2021-03-23 18:32:57.965 T:1070 INFO : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder ac3 2021-03-23 18:32:57.965 T:1070 INFO : CDVDDemuxFFmpeg::Read() stream change 2021-03-23 18:32:57.965 T:1070 INFO : ffmpeg[0x1b9ef30X]: Input #0, mpegts, from 'pvr://channels/tv/All%20channels/pvr.nextpvr_10220.pvr': 2021-03-23 18:32:57.966 T:1070 INFO : ffmpeg[0x1b9ef30X]: Duration: N/A, bitrate: N/A 2021-03-23 18:32:57.966 T:1070 INFO : ffmpeg[0x1b9ef30X]: Program 1 2021-03-23 18:32:57.966 T:1070 INFO : ffmpeg[0x1b9ef30X]: Stream #0:0[0x1439]: Video: h264 ([27][0][0][0] / 0x001B), none, 90k tbn 2021-03-23 18:32:57.966 T:1070 INFO : ffmpeg[0x1b9ef30X]: Stream #0:1[0x143a](eng): Audio: ac3 (AC-3 / 0x332D4341), 48000 Hz, 5.1(side), 384 kb/s 2021-03-23 18:32:57.966 T:1070 INFO : ffmpeg[0x1b9ef30X]: Stream #0:2[0x143b](spa): Audio: ac3 (AC-3 / 0x332D4341), 48000 Hz, stereo, 192 kb/s 2021-03-23 18:32:57.966 T:1070 INFO : ffmpeg[0x1b9ef30X]: Stream #0:3[0x143c]: Data: scte_35 2021-03-23 18:32:57.966 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 0 2021-03-23 18:32:57.966 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 1 2021-03-23 18:32:57.966 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 2 2021-03-23 18:32:57.966 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 3 2021-03-23 18:32:57.967 T:1070 INFO : Opening stream: 0 source: 256 2021-03-23 18:32:57.967 T:1070 WARNING : OpenStream - Unsupported stream 0. Stream disabled. 2021-03-23 18:32:57.967 T:1070 INFO : Opening stream: 1 source: 256 2021-03-23 18:32:57.967 T:1070 INFO : Finding audio codec for: 86019 2021-03-23 18:32:57.967 T:1071 DEBUG : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 0 2021-03-23 18:32:57.968 T:1070 INFO : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder ac3 2021-03-23 18:32:57.968 T:1070 DEBUG : CVideoPlayer::HandleMessages - player 1 reported state: 0 2021-03-23 18:32:57.969 T:936 DEBUG : OnAVChange: CApplication::OnAVChange 2021-03-23 18:32:57.969 T:1070 ERROR : ffmpeg[0x1b9ef30X]: [NULL] non-existing PPS 0 referenced 2021-03-23 18:32:57.977 T:1071 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:32:57.977 T:1071 DEBUG : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 0 2021-03-23 18:32:58.008 T:1070 ERROR : ffmpeg[0x1b9ef30X]: [NULL] non-existing PPS 0 referenced 2021-03-23 18:32:58.008 T:1070 DEBUG : CVideoPlayer::HandleMessages - player 1 reported state: 0 2021-03-23 18:32:58.009 T:936 DEBUG : OnAVChange: CApplication::OnAVChange 2021-03-23 18:32:58.012 T:1070 ERROR : ffmpeg[0x1b9ef30X]: [NULL] non-existing PPS 0 referenced 2021-03-23 18:32:58.566 T:1070 INFO : Skipped 28 duplicate messages.. 2021-03-23 18:32:58.566 T:1070 DEBUG : CDVDDemuxFFmpeg::ParsePacket() fetching extradata, extradata_size(56) 2021-03-23 18:32:58.568 T:1070 INFO : CDVDDemuxFFmpeg::Read() stream change 2021-03-23 18:32:58.568 T:1070 INFO : ffmpeg[0x1b9ef30X]: Input #0, mpegts, from 'pvr://channels/tv/All%20channels/pvr.nextpvr_10220.pvr': 2021-03-23 18:32:58.568 T:1070 INFO : ffmpeg[0x1b9ef30X]: Duration: N/A, bitrate: N/A 2021-03-23 18:32:58.568 T:1070 INFO : ffmpeg[0x1b9ef30X]: Program 1 2021-03-23 18:32:58.568 T:1070 INFO : ffmpeg[0x1b9ef30X]: Stream #0:0[0x1439]: Video: h264 ([27][0][0][0] / 0x001B), none, 1280x720, 90k tbn 2021-03-23 18:32:58.569 T:1070 INFO : ffmpeg[0x1b9ef30X]: Stream #0:1[0x143a](eng): Audio: ac3 (AC-3 / 0x332D4341), 48000 Hz, 5.1(side), 384 kb/s 2021-03-23 18:32:58.569 T:1070 INFO : ffmpeg[0x1b9ef30X]: Stream #0:2[0x143b](spa): Audio: ac3 (AC-3 / 0x332D4341), 48000 Hz, stereo, 192 kb/s 2021-03-23 18:32:58.569 T:1070 INFO : ffmpeg[0x1b9ef30X]: Stream #0:3[0x143c]: Data: scte_35 2021-03-23 18:32:58.569 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 0 2021-03-23 18:32:58.569 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 1 2021-03-23 18:32:58.569 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 2 2021-03-23 18:32:58.569 T:1070 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 3 2021-03-23 18:32:58.569 T:1070 INFO : Opening stream: 0 source: 256 2021-03-23 18:32:58.570 T:1070 INFO : Creating video codec with codec id: 27 2021-03-23 18:32:58.570 T:1070 INFO : CDVDVideoCodecDRMPRIME::Open - using decoder V4L2 mem2mem H.264 decoder wrapper 2021-03-23 18:32:58.571 T:1070 INFO : ffmpeg[0x1b9ef30X]: [h264_v4l2m2m] Using device /dev/video10 2021-03-23 18:32:58.571 T:1070 INFO : ffmpeg[0x1b9ef30X]: [h264_v4l2m2m] driver 'bcm2835-codec' on card 'bcm2835-codec-decode' in mplane mode 2021-03-23 18:32:58.571 T:1070 INFO : ffmpeg[0x1b9ef30X]: [h264_v4l2m2m] requesting formats: output=H264 capture=YU12 2021-03-23 18:32:58.584 T:1070 DEBUG : CVideoPlayerVideo::OpenStream - open stream with codec id: 27 2021-03-23 18:32:58.584 T:1070 INFO : Creating video thread 2021-03-23 18:32:58.585 T:1072 DEBUG : Thread VideoPlayerVideo start, auto delete: false 2021-03-23 18:32:58.585 T:1072 INFO : running thread: video_thread 2021-03-23 18:32:58.585 T:1072 DEBUG : CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0 2021-03-23 18:32:58.585 T:1070 INFO : Opening stream: 1 source: 256 2021-03-23 18:32:58.585 T:1070 DEBUG : CVideoPlayer::HandleMessages - player 2 reported state: 0 2021-03-23 18:32:58.586 T:1072 DEBUG : CVideoPlayerVideo - Stillframe left, switching to normal playback 2021-03-23 18:32:58.609 T:925 DEBUG : ffmpeg[(nil)X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format. 2021-03-23 18:32:58.623 T:1070 DEBUG : CVideoPlayer::HandleMessages - player 1 reported state: 0 2021-03-23 18:32:58.633 T:1071 INFO : Creating audio stream (codec id: 86019, channels: 6, sample rate: 48000, no pass-through) 2021-03-23 18:32:58.634 T:1071 DEBUG : CVideoPlayerAudio:: synctype set to 1: resample 2021-03-23 18:32:58.648 T:931 INFO : CActiveAESink::OpenSink - initialize sink 2021-03-23 18:32:58.653 T:925 DEBUG : FreeVisualisation() started 2021-03-23 18:32:58.653 T:925 DEBUG : FreeVisualisation() done 2021-03-23 18:32:58.717 T:1072 DEBUG : CRenderManager::Configure - change configuration. 1280x720. display: 1280x720. framerate: 0.00. 2021-03-23 18:32:58.733 T:925 DEBUG : DeleteRenderer - deleting renderer 2021-03-23 18:32:58.734 T:925 DEBUG : LinuxRendererGLES: Cleaning up GLES resources 2021-03-23 18:32:58.734 T:925 DEBUG : CDRMPlane::SupportsFormatAndModifier - found plane format (YU12) and modifier (0x0) 2021-03-23 18:32:58.734 T:925 DEBUG : CRenderManager::Configure - 5 2021-03-23 18:32:58.742 T:925 DEBUG : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 223 2021-03-23 18:32:58.742 T:925 DEBUG : CDRMAtomic::FlipPage - Execute modeset at next commit 2021-03-23 18:32:58.782 T:925 DEBUG : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 226 2021-03-23 18:32:58.790 T:974 DEBUG : OnAVChange: CApplication::OnAVChange 2021-03-23 18:32:58.791 T:1070 DEBUG : CVideoPlayer::HandleMessages - player started 2 2021-03-23 18:32:58.823 T:925 DEBUG : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 227 2021-03-23 18:32:58.847 T:931 DEBUG : CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=vc4hdmi0,DEV=0 2021-03-23 18:32:58.847 T:931 INFO : CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=vc4hdmi0,DEV=0" 2021-03-23 18:32:58.851 T:931 INFO : CAESinkALSA::Initialize - Opened device "hdmi:CARD=vc4hdmi0,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02" 2021-03-23 18:32:58.851 T:931 INFO : CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 2021-03-23 18:32:58.851 T:931 INFO : CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3 2021-03-23 18:32:58.851 T:931 DEBUG : CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600 2021-03-23 18:32:58.852 T:931 DEBUG : CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600 2021-03-23 18:32:58.852 T:931 DEBUG : CAESinkALSA::InitializeHW - Setting timeout to 200 ms 2021-03-23 18:32:58.852 T:931 DEBUG : CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 2021-03-23 18:32:58.852 T:931 DEBUG : CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR 2021-03-23 18:32:58.852 T:931 DEBUG : CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: FL FR) 2021-03-23 18:32:58.852 T:931 DEBUG : CActiveAESink::OpenSink - ALSA Initialized: 2021-03-23 18:32:58.852 T:931 DEBUG : Output Device : vc4-hdmi-0 (vc4hdmi0) 2021-03-23 18:32:58.852 T:931 DEBUG : Sample Rate : 48000 2021-03-23 18:32:58.852 T:931 DEBUG : Sample Format : AE_FMT_S24NE3 2021-03-23 18:32:58.852 T:931 DEBUG : Channel Count : 2 2021-03-23 18:32:58.852 T:931 DEBUG : Channel Layout: FL, FR 2021-03-23 18:32:58.852 T:931 DEBUG : Frames : 2400 2021-03-23 18:32:58.852 T:931 DEBUG : Frame Size : 6 2021-03-23 18:32:58.857 T:930 DEBUG : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2021-03-23 18:32:58.866 T:925 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:32:58.866 T:925 DEBUG : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 224 2021-03-23 18:32:58.895 T:1070 DEBUG : CVideoPlayer::HandleMessages - player started 1 2021-03-23 18:32:58.895 T:936 DEBUG : OnAVChange: CApplication::OnAVChange 2021-03-23 18:32:58.895 T:1070 DEBUG : VideoPlayer::Sync - Audio - pts: -1634110.000000, cache: 479118.626452, totalcache: 800000.011921 2021-03-23 18:32:58.895 T:1070 DEBUG : VideoPlayer::Sync - Video - pts: 26901.000000, cache: 50000.000000, totalcache: 100000.000000 2021-03-23 18:32:58.895 T:1072 DEBUG : CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-2834110.011921) 2021-03-23 18:32:58.895 T:993 DEBUG : OnAVStarted: CApplication::OnAVStarted 2021-03-23 18:32:58.895 T:1071 DEBUG : CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-2834110.011921), level: 2, cache: 478427.997452 2021-03-23 18:32:58.895 T:1071 DEBUG : CDVDAudio::Resume - resume audio stream 2021-03-23 18:32:58.896 T:930 DEBUG : ActiveAE - start sync of audio stream 2021-03-23 18:32:58.898 T:925 DEBUG : Activating window ID: 12005 2021-03-23 18:32:58.898 T:925 DEBUG : ------ Window Deinit (MyPVRGuide.xml) ------ 2021-03-23 18:32:58.898 T:1069 DEBUG : Thread epg-grid-refresh-timeline-items 2499801760 terminating 2021-03-23 18:32:58.898 T:925 DEBUG : FreeVisualisation() done 2021-03-23 18:32:58.904 T:925 DEBUG : ------ Window Init (VideoFullScreen.xml) ------ 2021-03-23 18:32:58.904 T:925 INFO : Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY 2021-03-23 18:32:58.933 T:993 DEBUG : OnAVChange: CApplication::OnAVChange 2021-03-23 18:32:58.933 T:1070 DEBUG : CDVDClock::SetSpeedAdjust - adjusted:-0.050000 2021-03-23 18:32:59.004 T:930 DEBUG : ActiveAE::SyncStream - average error of 737.854107, start adjusting 2021-03-23 18:32:59.441 T:1072 WARNING : OutputPicture - timeout waiting for buffer 2021-03-23 18:32:59.704 T:930 DEBUG : ActiveAE::SyncStream - average error 16.150948 below threshold of 30.000000 2021-03-23 18:32:59.942 T:1072 WARNING : OutputPicture - timeout waiting for buffer 2021-03-23 18:33:00.106 T:1071 INFO : CActiveAEStream::AddData - messy timestamps, increasing interval for measuring average error to 2023 ms 2021-03-23 18:33:00.483 T:1072 WARNING : OutputPicture - timeout waiting for buffer 2021-03-23 18:33:04.662 T:1070 INFO : Skipped 2 duplicate messages.. 2021-03-23 18:33:04.662 T:1070 DEBUG : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2021-03-23 18:33:05.151 T:1072 DEBUG : CPtsTracker: detected pattern of length 1: 16683.33, frameduration: 16683.333333 2021-03-23 18:33:06.776 T:1072 DEBUG : CalcFrameRate framerate was:25.000000 calculated:59.940060 2021-03-23 18:33:06.817 T:1072 DEBUG : CRenderManager::Configure - change configuration. 1280x720. display: 1280x720. framerate: 59.94. 2021-03-23 18:33:06.899 T:925 DEBUG : DeleteRenderer - deleting renderer 2021-03-23 18:33:06.899 T:925 DEBUG : CDRMPlane::SupportsFormatAndModifier - found plane format (YU12) and modifier (0x0) 2021-03-23 18:33:06.899 T:925 DEBUG : CRenderManager::Configure - 5 2021-03-23 18:33:06.901 T:925 DEBUG : CDRMAtomic::FlipPage - Execute modeset at next commit 2021-03-23 18:33:06.933 T:973 DEBUG : OnAVChange: CApplication::OnAVChange 2021-03-23 18:33:14.562 T:1070 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:33:14.562 T:1070 DEBUG : CDVDClock::SetSpeedAdjust - adjusted:-0.050000 2021-03-23 18:33:17.158 T:1071 INFO : CVideoPlayerAudio::Process - stream stalled 2021-03-23 18:33:17.167 T:1070 DEBUG : Stream stalled, start buffering. Audio: 0 - Video: 100 2021-03-23 18:33:17.167 T:1070 DEBUG : CVideoPlayer::FlushBuffers - flushing buffers 2021-03-23 18:33:17.191 T:1072 WARNING : OutputPicture - timeout waiting for buffer 2021-03-23 18:33:17.204 T:1072 DEBUG : CDVDVideoCodecDRMPRIME::Reset - flush buffers 2021-03-23 18:33:17.207 T:1071 DEBUG : CDVDAudio::Flush - flush audio stream 2021-03-23 18:33:17.207 T:1071 DEBUG : CDVDAudio::Pause - pausing audio stream 2021-03-23 18:33:17.211 T:1071 DEBUG : CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 2021-03-23 18:33:17.211 T:1070 DEBUG : CVideoPlayer::SetCaching - caching state 2 2021-03-23 18:33:17.211 T:1072 DEBUG : CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 2021-03-23 18:33:17.211 T:1070 DEBUG : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2021-03-23 18:33:17.211 T:1072 DEBUG : CVideoPlayerVideo - Stillframe left, switching to normal playback 2021-03-23 18:33:17.221 T:1071 DEBUG : CDVDAudio::Pause - pausing audio stream 2021-03-23 18:33:17.270 T:1070 DEBUG : CVideoPlayer::HandleMessages - player started 1 2021-03-23 18:33:17.270 T:936 DEBUG : OnAVChange: CApplication::OnAVChange 2021-03-23 18:33:17.462 T:1070 DEBUG : CVideoPlayer::HandleMessages - player started 2 2021-03-23 18:33:17.462 T:1070 DEBUG : CVideoPlayer::SetCaching - caching state 3 2021-03-23 18:33:17.462 T:1070 DEBUG : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2021-03-23 18:33:17.462 T:1070 DEBUG : CVideoPlayer::SetCaching - caching state 0 2021-03-23 18:33:17.462 T:1070 DEBUG : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2021-03-23 18:33:17.462 T:1070 DEBUG : VideoPlayer::Sync - Audio - pts: 15613867.000000, cache: 493174.035670, totalcache: 800000.011921 2021-03-23 18:33:17.462 T:1070 DEBUG : VideoPlayer::Sync - Video - pts: 18004900.000000, cache: 50000.000000, totalcache: 100000.000000 2021-03-23 18:33:17.462 T:1071 DEBUG : CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(14413866.988079), level: 16, cache: 497409.869336 2021-03-23 18:33:17.462 T:1072 DEBUG : CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(14413866.988079) 2021-03-23 18:33:17.463 T:1071 DEBUG : CDVDAudio::Resume - resume audio stream 2021-03-23 18:33:17.469 T:930 DEBUG : ActiveAE - start sync of audio stream 2021-03-23 18:33:17.606 T:930 DEBUG : ActiveAE::SyncStream - average error of 691.798928, start adjusting 2021-03-23 18:33:18.066 T:1072 WARNING : OutputPicture - timeout waiting for buffer 2021-03-23 18:33:18.257 T:930 DEBUG : ActiveAE::SyncStream - average error 0.702058, last average error: 691.798928 2021-03-23 18:33:18.357 T:930 DEBUG : ActiveAE::SyncStream - average error of -25.014531, start adjusting 2021-03-23 18:33:18.357 T:930 DEBUG : ActiveAE::SyncStream - average error -0.014531 below threshold of 30.000000 2021-03-23 18:33:18.607 T:1072 WARNING : OutputPicture - timeout waiting for buffer 2021-03-23 18:33:24.319 T:1072 INFO : Skipped 4 duplicate messages.. 2021-03-23 18:33:24.319 T:1072 DEBUG : CPtsTracker: detected pattern of length 1: 16683.33, frameduration: 16683.333333 2021-03-23 18:33:36.117 T:1070 DEBUG : CDVDClock::SetSpeedAdjust - adjusted:-0.050000 2021-03-23 18:33:37.242 T:985 DEBUG : PushCecKeypress - received key e0 duration 1001 2021-03-23 18:33:37.273 T:925 DEBUG : HandleKey: guide (0xe0) pressed, action is Stop 2021-03-23 18:33:37.273 T:925 INFO : CVideoPlayer::CloseFile() 2021-03-23 18:33:37.273 T:925 DEBUG : DeleteRenderer - deleting renderer 2021-03-23 18:33:37.274 T:925 INFO : VideoPlayer: waiting for threads to exit 2021-03-23 18:33:37.282 T:1070 INFO : CVideoPlayer::OnExit() 2021-03-23 18:33:37.282 T:1070 INFO : Closing stream player 1 2021-03-23 18:33:37.282 T:1070 INFO : Waiting for audio thread to exit 2021-03-23 18:33:37.282 T:1071 ERROR : Got MSGQ_ABORT or MSGO_IS_ERROR return true 2021-03-23 18:33:37.282 T:1071 INFO : thread end: CVideoPlayerAudio::OnExit() 2021-03-23 18:33:37.283 T:1071 DEBUG : Thread VideoPlayerAudio 2456134304 terminating 2021-03-23 18:33:37.283 T:1070 INFO : Closing audio device 2021-03-23 18:33:37.286 T:1072 WARNING : OutputPicture - timeout waiting for buffer 2021-03-23 18:33:37.287 T:1072 DEBUG : CPtsTracker: pattern lost on diff 0.000000, number of losses 1 2021-03-23 18:33:37.309 T:1070 DEBUG : CDVDAudio::Flush - flush audio stream 2021-03-23 18:33:37.310 T:930 DEBUG : CActiveAE::DiscardStream - audio stream deleted 2021-03-23 18:33:37.311 T:930 DEBUG : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2021-03-23 18:33:37.313 T:1070 INFO : Skipped 2 duplicate messages.. 2021-03-23 18:33:37.313 T:1070 INFO : Deleting audio codec 2021-03-23 18:33:37.313 T:1070 INFO : Closing stream player 2 2021-03-23 18:33:37.313 T:1070 INFO : waiting for video thread to exit 2021-03-23 18:33:37.314 T:931 INFO : CActiveAESink::OpenSink - initialize sink 2021-03-23 18:33:37.330 T:1072 INFO : thread end: video_thread 2021-03-23 18:33:37.330 T:1072 DEBUG : Thread VideoPlayerVideo 2447741600 terminating 2021-03-23 18:33:37.334 T:1070 INFO : deleting video codec 2021-03-23 18:33:37.341 T:936 DEBUG : connect replacing configured host mysql-pi01 with resolved host 192.168.1.177 2021-03-23 18:33:37.342 T:1070 DEBUG : AddOnLog: pvr.nextpvr: CloseLiveStream 2021-03-23 18:33:37.343 T:1070 DEBUG : AddOnLog: pvr.nextpvr: CloseHandle:63: 2021-03-23 18:33:37.343 T:1070 DEBUG : CInputStreamPVRChannel - ClosePVRStream - closed channel stream pvr://channels/tv/All%20channels/pvr.nextpvr_10220.pvr 2021-03-23 18:33:37.343 T:1070 DEBUG : Thread VideoPlayer 2464527008 terminating 2021-03-23 18:33:37.343 T:925 INFO : VideoPlayer: finished waiting 2021-03-23 18:33:37.343 T:925 INFO : CVideoPlayer::CloseFile() 2021-03-23 18:33:37.343 T:925 INFO : VideoPlayer: waiting for threads to exit 2021-03-23 18:33:37.344 T:925 INFO : VideoPlayer: finished waiting 2021-03-23 18:33:37.346 T:925 DEBUG : Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 2021-03-23 18:33:37.489 T:973 DEBUG : OnPlayBackStopped: CApplication::OnPlayBackStopped 2021-03-23 18:33:37.509 T:931 DEBUG : CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=vc4hdmi0,DEV=0 2021-03-23 18:33:37.509 T:931 INFO : CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=vc4hdmi0,DEV=0" 2021-03-23 18:33:37.515 T:931 INFO : CAESinkALSA::Initialize - Opened device "hdmi:CARD=vc4hdmi0,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 2021-03-23 18:33:37.516 T:931 INFO : CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 2021-03-23 18:33:37.516 T:931 INFO : CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3 2021-03-23 18:33:37.516 T:931 DEBUG : CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 2021-03-23 18:33:37.516 T:931 DEBUG : CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820 2021-03-23 18:33:37.516 T:931 DEBUG : CAESinkALSA::InitializeHW - Setting timeout to 200 ms 2021-03-23 18:33:37.517 T:931 DEBUG : CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 2021-03-23 18:33:37.517 T:931 DEBUG : CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR 2021-03-23 18:33:37.517 T:931 DEBUG : CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: FL FR) 2021-03-23 18:33:37.517 T:931 DEBUG : CActiveAESink::OpenSink - ALSA Initialized: 2021-03-23 18:33:37.517 T:931 DEBUG : Output Device : vc4-hdmi-0 (vc4hdmi0) 2021-03-23 18:33:37.517 T:931 DEBUG : Sample Rate : 44100 2021-03-23 18:33:37.517 T:931 DEBUG : Sample Format : AE_FMT_S24NE3 2021-03-23 18:33:37.517 T:931 DEBUG : Channel Count : 2 2021-03-23 18:33:37.517 T:931 DEBUG : Channel Layout: FL, FR 2021-03-23 18:33:37.517 T:931 DEBUG : Frames : 2205 2021-03-23 18:33:37.517 T:931 DEBUG : Frame Size : 6 2021-03-23 18:33:37.520 T:930 DEBUG : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2021-03-23 18:33:37.638 T:925 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:33:37.638 T:925 DEBUG : CGUIWindowManager::PreviousWindow: Deactivate 2021-03-23 18:33:37.638 T:925 DEBUG : ------ Window Deinit (VideoFullScreen.xml) ------ 2021-03-23 18:33:37.651 T:925 DEBUG : CGUIWindowManager::PreviousWindow: Activate new 2021-03-23 18:33:37.651 T:925 DEBUG : ------ Window Init (MyPVRGuide.xml) ------ 2021-03-23 18:33:37.655 T:1085 DEBUG : Thread epg-grid-refresh-timeline-items start, auto delete: false 2021-03-23 18:33:37.682 T:925 DEBUG : ffmpeg[(nil)X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format. 2021-03-23 18:33:37.859 T:925 DEBUG : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 226 2021-03-23 18:33:37.859 T:925 DEBUG : CDRMAtomic::FlipPage - Execute modeset at next commit 2021-03-23 18:33:37.919 T:925 DEBUG : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 224 2021-03-23 18:33:37.947 T:925 DEBUG : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 223 2021-03-23 18:33:37.989 T:925 DEBUG : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 225 2021-03-23 18:33:39.436 T:985 DEBUG : PushCecKeypress - received key a7 duration 0 2021-03-23 18:33:39.440 T:925 DEBUG : HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-03-23 18:33:39.654 T:985 DEBUG : PushCecKeypress - received key a7 duration 218 2021-03-23 18:33:40.286 T:985 DEBUG : PushCecKeypress - received key b duration 0 2021-03-23 18:33:40.315 T:925 DEBUG : HandleKey: 11 (0x0b, obc244) pressed, action is Select 2021-03-23 18:33:40.357 T:925 DEBUG : CPlayerCoreFactory::GetPlayers(pvr://channels/tv/All%20channels/pvr.nextpvr_10221.pvr) 2021-03-23 18:33:40.357 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: system rules 2021-03-23 18:33:40.357 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: matches rule: system rules 2021-03-23 18:33:40.357 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 2021-03-23 18:33:40.357 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 2021-03-23 18:33:40.357 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: rtmp 2021-03-23 18:33:40.357 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: rtsp 2021-03-23 18:33:40.357 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: streams 2021-03-23 18:33:40.357 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: dvd 2021-03-23 18:33:40.357 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: discimage 2021-03-23 18:33:40.357 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 2021-03-23 18:33:40.357 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: nsv 2021-03-23 18:33:40.357 T:925 DEBUG : CPlayerSelectionRule::GetPlayers: considering rule: radio 2021-03-23 18:33:40.358 T:925 DEBUG : CPlayerCoreFactory::GetPlayers: matched 0 rules with players 2021-03-23 18:33:40.358 T:925 DEBUG : CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 2021-03-23 18:33:40.358 T:925 DEBUG : CPlayerCoreFactory::GetPlayers: for video=1, audio=0 2021-03-23 18:33:40.358 T:925 DEBUG : CPlayerCoreFactory::GetPlayers: for video=1, audio=1 2021-03-23 18:33:40.358 T:925 DEBUG : CPlayerCoreFactory::GetPlayers: added 1 players 2021-03-23 18:33:40.365 T:925 DEBUG : Radio UECP (RDS) Processor - new CDVDRadioRDSData 2021-03-23 18:33:40.365 T:925 INFO : VideoPlayer::OpenFile: pvr://channels/tv/All%20channels/pvr.nextpvr_10221.pvr 2021-03-23 18:33:40.365 T:1086 DEBUG : Thread VideoPlayer start, auto delete: false 2021-03-23 18:33:40.365 T:925 DEBUG : OnPlayBackStarted: CApplication::OnPlayBackStarted 2021-03-23 18:33:40.366 T:936 DEBUG : connect replacing configured host mysql-pi01 with resolved host 192.168.1.177 2021-03-23 18:33:40.366 T:1086 INFO : Creating InputStream 2021-03-23 18:33:40.366 T:1086 DEBUG : AddOnLog: pvr.nextpvr: CloseLiveStream 2021-03-23 18:33:40.366 T:1086 ERROR : AddOnLog: pvr.nextpvr: Unknown live streaming state 0 0 1 2021-03-23 18:33:40.366 T:1086 INFO : AddOnLog: pvr.nextpvr: Calling Open(http://192.168.1.189:8866/live?channeloid=10221&client=XBMC-9120575bebe94fcd97b658aa3600b03a) on tsb! 2021-03-23 18:33:40.367 T:1086 DEBUG : AddOnLog: pvr.nextpvr: Buffer::Open() called! [ http://192.168.1.189:8866/live?channeloid=10221&client=XBMC-9120575bebe94fcd97b658aa3600b03a ] 2021-03-23 18:33:40.367 T:1086 DEBUG : CurlFile::Open(0xab950250) http://192.168.1.189:8866/live?channeloid=10221&client=XBMC-9120575bebe94fcd97b658aa3600b03a 2021-03-23 18:33:40.389 T:925 DEBUG : CPlayerGUIInfo::InitCurrentItem(pvr://channels/tv/All%20channels/pvr.nextpvr_10221.pvr) 2021-03-23 18:33:40.395 T:936 DEBUG : Loading settings for pvr://channels/tv/All%20channels/pvr.nextpvr_10221.pvr 2021-03-23 18:33:40.440 T:925 DEBUG : CPlayerGUIInfo::InitCurrentItem(pvr://channels/tv/All%20channels/pvr.nextpvr_10221.pvr) 2021-03-23 18:33:40.753 T:985 DEBUG : PushCecKeypress - received key b duration 467 2021-03-23 18:33:41.112 T:1086 DEBUG : CInputStreamPVRChannel - OpenPVRStream - opened channel stream pvr://channels/tv/All%20channels/pvr.nextpvr_10221.pvr 2021-03-23 18:33:41.112 T:1086 INFO : Creating Demuxer 2021-03-23 18:33:41.315 T:1086 DEBUG : Open - probing detected format [mpegts] 2021-03-23 18:33:41.316 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 0 2021-03-23 18:33:41.316 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 1 2021-03-23 18:33:41.316 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 2 2021-03-23 18:33:41.316 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 3 2021-03-23 18:33:41.316 T:1086 DEBUG : AddOnLog: pvr.nextpvr: seek length(0) 2021-03-23 18:33:41.316 T:1086 INFO : Opening stream: 0 source: 256 2021-03-23 18:33:41.316 T:1086 WARNING : OpenStream - Unsupported stream 0. Stream disabled. 2021-03-23 18:33:41.316 T:1086 INFO : Opening stream: 1 source: 256 2021-03-23 18:33:41.316 T:1086 INFO : Finding audio codec for: 86019 2021-03-23 18:33:41.318 T:1086 INFO : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder ac3 2021-03-23 18:33:41.318 T:1086 INFO : Creating audio thread 2021-03-23 18:33:41.318 T:1087 DEBUG : Thread VideoPlayerAudio start, auto delete: false 2021-03-23 18:33:41.318 T:1087 INFO : running thread: CVideoPlayerAudio::Process() 2021-03-23 18:33:41.318 T:1087 DEBUG : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 0 2021-03-23 18:33:41.319 T:1086 DEBUG : CVideoPlayer::SetCaching - caching state 2 2021-03-23 18:33:41.319 T:1086 DEBUG : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2021-03-23 18:33:41.319 T:1086 DEBUG : CVideoPlayer::HandleMessages - player 1 reported state: 0 2021-03-23 18:33:41.319 T:974 DEBUG : OnAVChange: CApplication::OnAVChange 2021-03-23 18:33:41.319 T:1086 DEBUG : CDVDClock::SetSpeedAdjust - adjusted:-0.050000 2021-03-23 18:33:41.329 T:1087 DEBUG : CDVDAudio::Pause - pausing audio stream 2021-03-23 18:33:41.357 T:925 DEBUG : FreeVisualisation() done 2021-03-23 18:33:41.434 T:1086 ERROR : ffmpeg[0x20be030X]: [NULL] non-existing PPS 0 referenced 2021-03-23 18:33:41.434 T:1086 INFO : CDVDDemuxFFmpeg::Read() stream change 2021-03-23 18:33:41.435 T:1086 INFO : ffmpeg[0x20be030X]: Input #0, mpegts, from 'pvr://channels/tv/All%20channels/pvr.nextpvr_10221.pvr': 2021-03-23 18:33:41.435 T:1086 INFO : ffmpeg[0x20be030X]: Duration: N/A, bitrate: N/A 2021-03-23 18:33:41.435 T:1086 INFO : ffmpeg[0x20be030X]: Program 2 2021-03-23 18:33:41.435 T:1086 INFO : ffmpeg[0x20be030X]: Stream #0:0[0x1441]: Video: h264 ([27][0][0][0] / 0x001B), none, 90k tbn 2021-03-23 18:33:41.435 T:1086 INFO : ffmpeg[0x20be030X]: Stream #0:1[0x1442](eng): Audio: ac3 (AC-3 / 0x332D4341), 0 channels 2021-03-23 18:33:41.435 T:1086 INFO : ffmpeg[0x20be030X]: Stream #0:2[0x1443](spa): Audio: ac3 (AC-3 / 0x332D4341), 0 channels (visual impaired) (descriptions) 2021-03-23 18:33:41.435 T:1086 INFO : ffmpeg[0x20be030X]: Stream #0:3[0x1444]: Data: scte_35 2021-03-23 18:33:41.435 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 0 2021-03-23 18:33:41.435 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 1 2021-03-23 18:33:41.435 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 2 2021-03-23 18:33:41.435 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 3 2021-03-23 18:33:41.436 T:1086 INFO : Opening stream: 0 source: 256 2021-03-23 18:33:41.436 T:1086 WARNING : OpenStream - Unsupported stream 0. Stream disabled. 2021-03-23 18:33:41.436 T:1086 INFO : Opening stream: 1 source: 256 2021-03-23 18:33:41.470 T:1086 ERROR : ffmpeg[0x20be030X]: [NULL] non-existing PPS 0 referenced 2021-03-23 18:33:41.470 T:1086 DEBUG : CVideoPlayer::HandleMessages - player 1 reported state: 0 2021-03-23 18:33:41.475 T:1086 ERROR : ffmpeg[0x20be030X]: [NULL] non-existing PPS 0 referenced 2021-03-23 18:33:41.641 T:1086 INFO : Skipped 9 duplicate messages.. 2021-03-23 18:33:41.641 T:1086 INFO : CDVDDemuxFFmpeg::Read() stream change 2021-03-23 18:33:41.641 T:1086 INFO : ffmpeg[0x20be030X]: Input #0, mpegts, from 'pvr://channels/tv/All%20channels/pvr.nextpvr_10221.pvr': 2021-03-23 18:33:41.641 T:1086 INFO : ffmpeg[0x20be030X]: Duration: N/A, bitrate: N/A 2021-03-23 18:33:41.641 T:1086 INFO : ffmpeg[0x20be030X]: Program 2 2021-03-23 18:33:41.641 T:1086 INFO : ffmpeg[0x20be030X]: Stream #0:0[0x1441]: Video: h264 ([27][0][0][0] / 0x001B), none, 90k tbn 2021-03-23 18:33:41.642 T:1086 INFO : ffmpeg[0x20be030X]: Stream #0:1[0x1442](eng): Audio: ac3 (AC-3 / 0x332D4341), 0 channels 2021-03-23 18:33:41.642 T:1086 INFO : ffmpeg[0x20be030X]: Stream #0:2[0x1443](spa): Audio: ac3 (AC-3 / 0x332D4341), 48000 Hz, stereo, 128 kb/s (visual impaired) (descriptions) 2021-03-23 18:33:41.642 T:1086 INFO : ffmpeg[0x20be030X]: Stream #0:3[0x1444]: Data: scte_35 2021-03-23 18:33:41.642 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 0 2021-03-23 18:33:41.642 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 1 2021-03-23 18:33:41.642 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 2 2021-03-23 18:33:41.642 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 3 2021-03-23 18:33:41.642 T:1086 INFO : Opening stream: 0 source: 256 2021-03-23 18:33:41.642 T:1086 WARNING : OpenStream - Unsupported stream 0. Stream disabled. 2021-03-23 18:33:41.643 T:1086 INFO : Opening stream: 2 source: 256 2021-03-23 18:33:41.643 T:1086 INFO : Finding audio codec for: 86019 2021-03-23 18:33:41.644 T:1086 INFO : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder ac3 2021-03-23 18:33:41.645 T:1086 ERROR : ffmpeg[0x20be030X]: [NULL] non-existing PPS 0 referenced 2021-03-23 18:33:41.645 T:1086 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:33:41.645 T:1086 INFO : CDVDDemuxFFmpeg::Read() stream change 2021-03-23 18:33:41.646 T:1086 INFO : ffmpeg[0x20be030X]: Input #0, mpegts, from 'pvr://channels/tv/All%20channels/pvr.nextpvr_10221.pvr': 2021-03-23 18:33:41.646 T:1086 INFO : ffmpeg[0x20be030X]: Duration: N/A, bitrate: N/A 2021-03-23 18:33:41.646 T:1086 INFO : ffmpeg[0x20be030X]: Program 2 2021-03-23 18:33:41.646 T:1086 INFO : ffmpeg[0x20be030X]: Stream #0:0[0x1441]: Video: h264 ([27][0][0][0] / 0x001B), none, 90k tbn 2021-03-23 18:33:41.646 T:1086 INFO : ffmpeg[0x20be030X]: Stream #0:1[0x1442](eng): Audio: ac3 (AC-3 / 0x332D4341), 48000 Hz, 5.1(side), 384 kb/s 2021-03-23 18:33:41.646 T:1086 INFO : ffmpeg[0x20be030X]: Stream #0:2[0x1443](spa): Audio: ac3 (AC-3 / 0x332D4341), 48000 Hz, stereo, 128 kb/s (visual impaired) (descriptions) 2021-03-23 18:33:41.646 T:1086 INFO : ffmpeg[0x20be030X]: Stream #0:3[0x1444]: Data: scte_35 2021-03-23 18:33:41.646 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 0 2021-03-23 18:33:41.646 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 1 2021-03-23 18:33:41.646 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 2 2021-03-23 18:33:41.646 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 3 2021-03-23 18:33:41.647 T:1086 INFO : Opening stream: 0 source: 256 2021-03-23 18:33:41.647 T:1086 WARNING : OpenStream - Unsupported stream 0. Stream disabled. 2021-03-23 18:33:41.647 T:1086 INFO : Opening stream: 1 source: 256 2021-03-23 18:33:41.647 T:1086 INFO : Finding audio codec for: 86019 2021-03-23 18:33:41.649 T:1086 INFO : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder ac3 2021-03-23 18:33:41.652 T:1087 DEBUG : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 0 2021-03-23 18:33:41.693 T:1086 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:33:41.693 T:1086 ERROR : ffmpeg[0x20be030X]: [NULL] non-existing PPS 0 referenced 2021-03-23 18:33:41.693 T:1086 DEBUG : CVideoPlayer::HandleMessages - player 1 reported state: 0 2021-03-23 18:33:41.693 T:993 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:33:41.693 T:993 DEBUG : OnAVChange: CApplication::OnAVChange 2021-03-23 18:33:41.699 T:1086 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:33:41.699 T:1086 ERROR : ffmpeg[0x20be030X]: [NULL] non-existing PPS 0 referenced 2021-03-23 18:33:42.469 T:1086 INFO : Skipped 38 duplicate messages.. 2021-03-23 18:33:42.469 T:1086 DEBUG : CDVDDemuxFFmpeg::ParsePacket() fetching extradata, extradata_size(56) 2021-03-23 18:33:42.471 T:1086 INFO : CDVDDemuxFFmpeg::Read() stream change 2021-03-23 18:33:42.471 T:1086 INFO : ffmpeg[0x20be030X]: Input #0, mpegts, from 'pvr://channels/tv/All%20channels/pvr.nextpvr_10221.pvr': 2021-03-23 18:33:42.471 T:1086 INFO : ffmpeg[0x20be030X]: Duration: N/A, bitrate: N/A 2021-03-23 18:33:42.471 T:1086 INFO : ffmpeg[0x20be030X]: Program 2 2021-03-23 18:33:42.471 T:1086 INFO : ffmpeg[0x20be030X]: Stream #0:0[0x1441]: Video: h264 ([27][0][0][0] / 0x001B), none, 1280x720, 90k tbn 2021-03-23 18:33:42.471 T:1086 INFO : ffmpeg[0x20be030X]: Stream #0:1[0x1442](eng): Audio: ac3 (AC-3 / 0x332D4341), 48000 Hz, 5.1(side), 384 kb/s 2021-03-23 18:33:42.472 T:1086 INFO : ffmpeg[0x20be030X]: Stream #0:2[0x1443](spa): Audio: ac3 (AC-3 / 0x332D4341), 48000 Hz, stereo, 128 kb/s (visual impaired) (descriptions) 2021-03-23 18:33:42.472 T:1086 INFO : ffmpeg[0x20be030X]: Stream #0:3[0x1444]: Data: scte_35 2021-03-23 18:33:42.472 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 0 2021-03-23 18:33:42.472 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 1 2021-03-23 18:33:42.472 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 2 2021-03-23 18:33:42.472 T:1086 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 3 2021-03-23 18:33:42.472 T:1086 INFO : Opening stream: 0 source: 256 2021-03-23 18:33:42.472 T:1086 INFO : Creating video codec with codec id: 27 2021-03-23 18:33:42.472 T:1086 INFO : CDVDVideoCodecDRMPRIME::Open - using decoder V4L2 mem2mem H.264 decoder wrapper 2021-03-23 18:33:42.473 T:1086 INFO : ffmpeg[0x20be030X]: [h264_v4l2m2m] Using device /dev/video10 2021-03-23 18:33:42.474 T:1086 INFO : ffmpeg[0x20be030X]: [h264_v4l2m2m] driver 'bcm2835-codec' on card 'bcm2835-codec-decode' in mplane mode 2021-03-23 18:33:42.474 T:1086 INFO : ffmpeg[0x20be030X]: [h264_v4l2m2m] requesting formats: output=H264 capture=YU12 2021-03-23 18:33:42.484 T:1086 DEBUG : CVideoPlayerVideo::OpenStream - open stream with codec id: 27 2021-03-23 18:33:42.484 T:1086 INFO : Creating video thread 2021-03-23 18:33:42.484 T:1088 DEBUG : Thread VideoPlayerVideo start, auto delete: false 2021-03-23 18:33:42.485 T:1088 INFO : running thread: video_thread 2021-03-23 18:33:42.485 T:1088 DEBUG : CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0 2021-03-23 18:33:42.485 T:1086 INFO : Opening stream: 1 source: 256 2021-03-23 18:33:42.485 T:1086 DEBUG : CVideoPlayer::HandleMessages - player 2 reported state: 0 2021-03-23 18:33:42.485 T:1088 DEBUG : CVideoPlayerVideo - Stillframe left, switching to normal playback 2021-03-23 18:33:42.516 T:1086 DEBUG : CVideoPlayer::HandleMessages - player 1 reported state: 0 2021-03-23 18:33:42.524 T:925 DEBUG : ffmpeg[(nil)X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format. 2021-03-23 18:33:42.574 T:925 DEBUG : FreeVisualisation() started 2021-03-23 18:33:42.575 T:925 DEBUG : FreeVisualisation() done 2021-03-23 18:33:42.581 T:1088 DEBUG : CRenderManager::Configure - change configuration. 1280x720. display: 1280x720. framerate: 0.00. 2021-03-23 18:33:42.582 T:1087 INFO : Creating audio stream (codec id: 86019, channels: 6, sample rate: 48000, no pass-through) 2021-03-23 18:33:42.584 T:1087 DEBUG : CVideoPlayerAudio:: synctype set to 1: resample 2021-03-23 18:33:42.621 T:931 INFO : CActiveAESink::OpenSink - initialize sink 2021-03-23 18:33:42.651 T:925 DEBUG : DeleteRenderer - deleting renderer 2021-03-23 18:33:42.651 T:925 DEBUG : LinuxRendererGLES: Cleaning up GLES resources 2021-03-23 18:33:42.651 T:925 DEBUG : CDRMPlane::SupportsFormatAndModifier - found plane format (YU12) and modifier (0x0) 2021-03-23 18:33:42.651 T:925 DEBUG : CRenderManager::Configure - 5 2021-03-23 18:33:42.658 T:925 DEBUG : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 226 2021-03-23 18:33:42.658 T:925 DEBUG : CDRMAtomic::FlipPage - Execute modeset at next commit 2021-03-23 18:33:42.678 T:993 DEBUG : OnAVChange: CApplication::OnAVChange 2021-03-23 18:33:42.678 T:1086 DEBUG : CVideoPlayer::HandleMessages - player started 2 2021-03-23 18:33:42.698 T:925 DEBUG : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 224 2021-03-23 18:33:42.740 T:925 DEBUG : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 223 2021-03-23 18:33:42.782 T:925 DEBUG : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 225 2021-03-23 18:33:42.820 T:931 DEBUG : CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=vc4hdmi0,DEV=0 2021-03-23 18:33:42.820 T:931 INFO : CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=vc4hdmi0,DEV=0" 2021-03-23 18:33:42.824 T:931 INFO : CAESinkALSA::Initialize - Opened device "hdmi:CARD=vc4hdmi0,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02" 2021-03-23 18:33:42.825 T:931 INFO : CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 2021-03-23 18:33:42.825 T:931 INFO : CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3 2021-03-23 18:33:42.825 T:931 DEBUG : CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600 2021-03-23 18:33:42.825 T:931 DEBUG : CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600 2021-03-23 18:33:42.825 T:931 DEBUG : CAESinkALSA::InitializeHW - Setting timeout to 200 ms 2021-03-23 18:33:42.825 T:931 DEBUG : CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 2021-03-23 18:33:42.825 T:931 DEBUG : CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR 2021-03-23 18:33:42.825 T:931 DEBUG : CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: FL FR) 2021-03-23 18:33:42.825 T:931 DEBUG : CActiveAESink::OpenSink - ALSA Initialized: 2021-03-23 18:33:42.826 T:931 DEBUG : Output Device : vc4-hdmi-0 (vc4hdmi0) 2021-03-23 18:33:42.826 T:931 DEBUG : Sample Rate : 48000 2021-03-23 18:33:42.826 T:931 DEBUG : Sample Format : AE_FMT_S24NE3 2021-03-23 18:33:42.826 T:931 DEBUG : Channel Count : 2 2021-03-23 18:33:42.826 T:931 DEBUG : Channel Layout: FL, FR 2021-03-23 18:33:42.826 T:931 DEBUG : Frames : 2400 2021-03-23 18:33:42.826 T:931 DEBUG : Frame Size : 6 2021-03-23 18:33:42.831 T:930 DEBUG : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2021-03-23 18:33:42.850 T:1086 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:33:42.850 T:1086 DEBUG : CVideoPlayer::HandleMessages - player started 1 2021-03-23 18:33:42.850 T:993 DEBUG : OnAVChange: CApplication::OnAVChange 2021-03-23 18:33:42.850 T:1086 DEBUG : VideoPlayer::Sync - Audio - pts: -1718721.000000, cache: 511422.459785, totalcache: 800000.011921 2021-03-23 18:33:42.850 T:1086 DEBUG : VideoPlayer::Sync - Video - pts: 17368.000000, cache: 50000.000000, totalcache: 100000.000000 2021-03-23 18:33:42.850 T:1088 DEBUG : CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-2918721.011921) 2021-03-23 18:33:42.850 T:936 DEBUG : OnAVStarted: CApplication::OnAVStarted 2021-03-23 18:33:42.850 T:1087 DEBUG : CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-2918721.011921), level: 2, cache: 496727.348785 2021-03-23 18:33:42.850 T:1087 DEBUG : CDVDAudio::Resume - resume audio stream 2021-03-23 18:33:42.851 T:930 DEBUG : ActiveAE - start sync of audio stream 2021-03-23 18:33:42.853 T:1086 DEBUG : CDVDClock::SetSpeedAdjust - adjusted:-0.050000 2021-03-23 18:33:42.857 T:925 DEBUG : Activating window ID: 12005 2021-03-23 18:33:42.858 T:925 DEBUG : ------ Window Deinit (MyPVRGuide.xml) ------ 2021-03-23 18:33:42.859 T:1085 DEBUG : Thread epg-grid-refresh-timeline-items 2499801760 terminating 2021-03-23 18:33:42.859 T:925 DEBUG : FreeVisualisation() done 2021-03-23 18:33:42.862 T:925 DEBUG : ------ Window Init (VideoFullScreen.xml) ------ 2021-03-23 18:33:42.898 T:936 DEBUG : OnAVChange: CApplication::OnAVChange 2021-03-23 18:33:42.977 T:930 DEBUG : ActiveAE::SyncStream - average error of 721.016655, start adjusting 2021-03-23 18:33:43.399 T:1088 WARNING : OutputPicture - timeout waiting for buffer 2021-03-23 18:33:43.678 T:930 DEBUG : ActiveAE::SyncStream - average error 0.873303 below threshold of 30.000000 2021-03-23 18:33:43.900 T:1088 WARNING : OutputPicture - timeout waiting for buffer 2021-03-23 18:33:44.079 T:1087 INFO : CActiveAEStream::AddData - messy timestamps, increasing interval for measuring average error to 2024 ms 2021-03-23 18:33:44.441 T:1088 WARNING : OutputPicture - timeout waiting for buffer 2021-03-23 18:33:47.476 T:1086 INFO : Skipped 2 duplicate messages.. 2021-03-23 18:33:47.476 T:1086 DEBUG : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2021-03-23 18:33:49.153 T:1088 DEBUG : CPtsTracker: detected pattern of length 1: 16683.33, frameduration: 16683.333333 2021-03-23 18:33:50.817 T:1088 DEBUG : CalcFrameRate framerate was:25.000000 calculated:59.940060 2021-03-23 18:33:50.819 T:1088 DEBUG : CRenderManager::Configure - change configuration. 1280x720. display: 1280x720. framerate: 59.94. 2021-03-23 18:33:50.899 T:925 DEBUG : DeleteRenderer - deleting renderer 2021-03-23 18:33:50.899 T:925 DEBUG : CDRMPlane::SupportsFormatAndModifier - found plane format (YU12) and modifier (0x0) 2021-03-23 18:33:50.899 T:925 DEBUG : CRenderManager::Configure - 5 2021-03-23 18:33:50.901 T:925 DEBUG : CDRMAtomic::FlipPage - Execute modeset at next commit 2021-03-23 18:33:50.901 T:973 DEBUG : OnAVChange: CApplication::OnAVChange 2021-03-23 18:33:58.534 T:1086 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:33:58.534 T:1086 DEBUG : CDVDClock::SetSpeedAdjust - adjusted:-0.050000 2021-03-23 18:33:59.080 T:930 WARNING : ActiveAE - large audio sync error: -89362.824774 2021-03-23 18:33:59.080 T:930 WARNING : ActiveAE - large audio sync error: -89363.309677 2021-03-23 18:34:01.144 T:1087 INFO : CVideoPlayerAudio::Process - stream stalled 2021-03-23 18:34:01.149 T:1086 DEBUG : Stream stalled, start buffering. Audio: 0 - Video: 100 2021-03-23 18:34:01.150 T:1086 DEBUG : CVideoPlayer::FlushBuffers - flushing buffers 2021-03-23 18:34:01.168 T:1088 DEBUG : CDVDVideoCodecDRMPRIME::Reset - flush buffers 2021-03-23 18:34:01.180 T:1087 DEBUG : CDVDAudio::Flush - flush audio stream 2021-03-23 18:34:01.180 T:1087 DEBUG : CDVDAudio::Pause - pausing audio stream 2021-03-23 18:34:01.180 T:1087 DEBUG : CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 2021-03-23 18:34:01.180 T:1086 DEBUG : CVideoPlayer::SetCaching - caching state 2 2021-03-23 18:34:01.180 T:1088 DEBUG : CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 2021-03-23 18:34:01.180 T:1086 DEBUG : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2021-03-23 18:34:01.181 T:1088 DEBUG : CVideoPlayerVideo - Stillframe left, switching to normal playback 2021-03-23 18:34:01.190 T:1087 DEBUG : CDVDAudio::Pause - pausing audio stream 2021-03-23 18:34:01.199 T:1086 DEBUG : CVideoPlayer::HandleMessages - player started 1 2021-03-23 18:34:01.200 T:992 DEBUG : OnAVChange: CApplication::OnAVChange 2021-03-23 18:34:01.474 T:1086 DEBUG : CVideoPlayer::HandleMessages - player started 2 2021-03-23 18:34:01.474 T:1086 DEBUG : CVideoPlayer::SetCaching - caching state 3 2021-03-23 18:34:01.474 T:1086 DEBUG : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2021-03-23 18:34:01.474 T:1086 DEBUG : CVideoPlayer::SetCaching - caching state 0 2021-03-23 18:34:01.474 T:1086 DEBUG : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2021-03-23 18:34:01.474 T:1086 DEBUG : VideoPlayer::Sync - Audio - pts: 15753256.000000, cache: 305534.203066, totalcache: 800000.011921 2021-03-23 18:34:01.474 T:1086 DEBUG : VideoPlayer::Sync - Video - pts: 17995367.000000, cache: 50000.000000, totalcache: 100000.000000 2021-03-23 18:34:01.474 T:1087 DEBUG : CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(14553255.988079), level: 16, cache: 421528.852066 2021-03-23 18:34:01.474 T:1087 DEBUG : CDVDAudio::Resume - resume audio stream 2021-03-23 18:34:01.476 T:1088 DEBUG : CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(14553255.988079) 2021-03-23 18:34:01.481 T:930 DEBUG : ActiveAE - start sync of audio stream 2021-03-23 18:34:01.630 T:930 DEBUG : ActiveAE::SyncStream - average error of 717.089584, start adjusting 2021-03-23 18:34:02.067 T:1088 WARNING : OutputPicture - timeout waiting for buffer 2021-03-23 18:34:02.280 T:930 DEBUG : ActiveAE::SyncStream - average error 20.603772, last average error: 717.089584 2021-03-23 18:34:02.380 T:930 DEBUG : ActiveAE::SyncStream - average error of -4.412588, start adjusting 2021-03-23 18:34:02.380 T:930 DEBUG : ActiveAE::SyncStream - average error -0.016755 below threshold of 30.000000 2021-03-23 18:34:02.608 T:1088 WARNING : OutputPicture - timeout waiting for buffer 2021-03-23 18:34:08.193 T:1088 INFO : Skipped 4 duplicate messages.. 2021-03-23 18:34:08.193 T:1088 DEBUG : CPtsTracker: detected pattern of length 1: 16683.33, frameduration: 16683.333333 2021-03-23 18:34:16.667 T:985 DEBUG : PushCecKeypress - received key e0 duration 1001 2021-03-23 18:34:16.690 T:925 DEBUG : HandleKey: guide (0xe0) pressed, action is Stop 2021-03-23 18:34:16.690 T:925 INFO : CVideoPlayer::CloseFile() 2021-03-23 18:34:16.690 T:925 DEBUG : DeleteRenderer - deleting renderer 2021-03-23 18:34:16.690 T:925 INFO : VideoPlayer: waiting for threads to exit 2021-03-23 18:34:16.691 T:1086 INFO : CVideoPlayer::OnExit() 2021-03-23 18:34:16.691 T:1086 INFO : Closing stream player 1 2021-03-23 18:34:16.691 T:1086 INFO : Waiting for audio thread to exit 2021-03-23 18:34:16.703 T:1088 WARNING : OutputPicture - timeout waiting for buffer 2021-03-23 18:34:16.704 T:1088 DEBUG : CPtsTracker: pattern lost on diff 0.000000, number of losses 1 2021-03-23 18:34:16.733 T:1087 INFO : thread end: CVideoPlayerAudio::OnExit() 2021-03-23 18:34:16.733 T:1087 DEBUG : Thread VideoPlayerAudio 2447741600 terminating 2021-03-23 18:34:16.733 T:1086 INFO : Closing audio device 2021-03-23 18:34:16.782 T:1086 DEBUG : CDVDAudio::Flush - flush audio stream 2021-03-23 18:34:16.783 T:930 DEBUG : CActiveAE::DiscardStream - audio stream deleted 2021-03-23 18:34:16.783 T:930 DEBUG : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2021-03-23 18:34:16.786 T:1086 INFO : Skipped 2 duplicate messages.. 2021-03-23 18:34:16.786 T:1086 INFO : Deleting audio codec 2021-03-23 18:34:16.786 T:1086 INFO : Closing stream player 2 2021-03-23 18:34:16.786 T:1086 INFO : waiting for video thread to exit 2021-03-23 18:34:16.787 T:1088 INFO : thread end: video_thread 2021-03-23 18:34:16.787 T:1088 DEBUG : Thread VideoPlayerVideo 2456134304 terminating 2021-03-23 18:34:16.788 T:931 INFO : CActiveAESink::OpenSink - initialize sink 2021-03-23 18:34:16.791 T:1086 INFO : deleting video codec 2021-03-23 18:34:16.798 T:936 DEBUG : connect replacing configured host mysql-pi01 with resolved host 192.168.1.177 2021-03-23 18:34:16.798 T:1086 DEBUG : AddOnLog: pvr.nextpvr: CloseLiveStream 2021-03-23 18:34:16.799 T:1086 DEBUG : AddOnLog: pvr.nextpvr: CloseHandle:63: 2021-03-23 18:34:16.799 T:1086 DEBUG : CInputStreamPVRChannel - ClosePVRStream - closed channel stream pvr://channels/tv/All%20channels/pvr.nextpvr_10221.pvr 2021-03-23 18:34:16.799 T:1086 DEBUG : Thread VideoPlayer 2464527008 terminating 2021-03-23 18:34:16.799 T:925 INFO : VideoPlayer: finished waiting 2021-03-23 18:34:16.799 T:925 INFO : CVideoPlayer::CloseFile() 2021-03-23 18:34:16.799 T:925 INFO : VideoPlayer: waiting for threads to exit 2021-03-23 18:34:16.799 T:925 INFO : VideoPlayer: finished waiting 2021-03-23 18:34:16.804 T:925 DEBUG : Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 2021-03-23 18:34:16.846 T:992 DEBUG : OnPlayBackStopped: CApplication::OnPlayBackStopped 2021-03-23 18:34:16.875 T:925 DEBUG : CGUIWindowManager::PreviousWindow: Deactivate 2021-03-23 18:34:16.875 T:925 DEBUG : ------ Window Deinit (VideoFullScreen.xml) ------ 2021-03-23 18:34:16.896 T:925 DEBUG : CGUIWindowManager::PreviousWindow: Activate new 2021-03-23 18:34:16.896 T:925 DEBUG : ------ Window Init (MyPVRGuide.xml) ------ 2021-03-23 18:34:16.905 T:1105 DEBUG : Thread epg-grid-refresh-timeline-items start, auto delete: false 2021-03-23 18:34:16.948 T:925 DEBUG : ffmpeg[(nil)X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format. 2021-03-23 18:34:16.982 T:931 DEBUG : CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=vc4hdmi0,DEV=0 2021-03-23 18:34:16.982 T:931 INFO : CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=vc4hdmi0,DEV=0" 2021-03-23 18:34:16.988 T:931 INFO : CAESinkALSA::Initialize - Opened device "hdmi:CARD=vc4hdmi0,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 2021-03-23 18:34:16.989 T:931 INFO : CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 2021-03-23 18:34:16.989 T:931 INFO : CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3 2021-03-23 18:34:16.989 T:931 DEBUG : CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 2021-03-23 18:34:16.990 T:931 DEBUG : CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820 2021-03-23 18:34:16.990 T:931 DEBUG : CAESinkALSA::InitializeHW - Setting timeout to 200 ms 2021-03-23 18:34:16.990 T:931 DEBUG : CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 2021-03-23 18:34:16.990 T:931 DEBUG : CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR 2021-03-23 18:34:16.990 T:931 DEBUG : CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: FL FR) 2021-03-23 18:34:16.990 T:931 DEBUG : CActiveAESink::OpenSink - ALSA Initialized: 2021-03-23 18:34:16.990 T:931 DEBUG : Output Device : vc4-hdmi-0 (vc4hdmi0) 2021-03-23 18:34:16.990 T:931 DEBUG : Sample Rate : 44100 2021-03-23 18:34:16.990 T:931 DEBUG : Sample Format : AE_FMT_S24NE3 2021-03-23 18:34:16.990 T:931 DEBUG : Channel Count : 2 2021-03-23 18:34:16.990 T:931 DEBUG : Channel Layout: FL, FR 2021-03-23 18:34:16.990 T:931 DEBUG : Frames : 2205 2021-03-23 18:34:16.990 T:931 DEBUG : Frame Size : 6 2021-03-23 18:34:16.993 T:930 DEBUG : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2021-03-23 18:34:17.147 T:925 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:34:17.147 T:925 DEBUG : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 226 2021-03-23 18:34:17.147 T:925 DEBUG : CDRMAtomic::FlipPage - Execute modeset at next commit 2021-03-23 18:34:17.211 T:925 DEBUG : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 224 2021-03-23 18:34:17.239 T:925 DEBUG : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 223 2021-03-23 18:34:17.280 T:925 DEBUG : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 225 2021-03-23 18:34:18.570 T:985 DEBUG : PushCecKeypress - received key d8 duration 0 2021-03-23 18:34:18.577 T:925 DEBUG : HandleKey: menu (0xd8) pressed, action is Back 2021-03-23 18:34:18.577 T:925 DEBUG : Activating window ID: 10000 2021-03-23 18:34:18.826 T:925 DEBUG : ------ Window Deinit (MyPVRGuide.xml) ------ 2021-03-23 18:34:18.827 T:1105 DEBUG : Thread epg-grid-refresh-timeline-items 2499801760 terminating 2021-03-23 18:34:18.827 T:925 DEBUG : FreeVisualisation() done 2021-03-23 18:34:18.856 T:925 DEBUG : ------ Window Init (Home.xml) ------ 2021-03-23 18:34:18.856 T:925 DEBUG : CDirectoryProvider[library://video/movies/]: refreshing.. 2021-03-23 18:34:18.857 T:925 DEBUG : CDirectoryProvider[library://video/tvshows/]: refreshing.. 2021-03-23 18:34:18.861 T:925 DEBUG : CDirectoryProvider[library://music/]: refreshing.. 2021-03-23 18:34:18.862 T:993 DEBUG : [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultGenre.png' 2021-03-23 18:34:18.864 T:974 DEBUG : [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultCountry.png' 2021-03-23 18:34:18.865 T:993 DEBUG : [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultStudios.png' 2021-03-23 18:34:18.866 T:993 DEBUG : [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultTags.png' 2021-03-23 18:34:18.868 T:974 DEBUG : [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultDirector.png' 2021-03-23 18:34:18.869 T:993 DEBUG : [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultTVShowTitle.png' 2021-03-23 18:34:18.870 T:974 DEBUG : [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultGenre.png' 2021-03-23 18:34:18.870 T:993 DEBUG : [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultYear.png' 2021-03-23 18:34:18.874 T:974 DEBUG : [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultSets.png' 2021-03-23 18:34:18.876 T:974 DEBUG : [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultStudios.png' 2021-03-23 18:34:18.878 T:974 DEBUG : [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultTags.png' 2021-03-23 18:34:18.880 T:974 DEBUG : [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultMovieTitle.png' 2021-03-23 18:34:18.881 T:974 DEBUG : [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultYear.png' 2021-03-23 18:34:18.884 T:936 DEBUG : CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update 2021-03-23 18:34:18.885 T:936 DEBUG : connect replacing configured host mysql-pi01 with resolved host 192.168.1.177 2021-03-23 18:34:18.995 T:985 DEBUG : PushCecKeypress - received key d8 duration 426 2021-03-23 18:34:19.010 T:936 DEBUG : connect replacing configured host mysql-pi01 with resolved host 192.168.1.177 2021-03-23 18:34:19.084 T:936 DEBUG : GetRecentlyAddedAlbumSongs() query: SELECT songview.*, songartistview.* FROM (SELECT idAlbum, dateAdded FROM album ORDER BY dateAdded DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum = recentalbums.idAlbum JOIN songartistview ON songview.idSong = songartistview.idSong ORDER BY recentalbums.dateAdded DESC, songview.idAlbum DESC, songview.idSong, songartistview.idRole, songartistview.iOrder 2021-03-23 18:34:19.279 T:991 DEBUG : CurlFile::ParseAndCorrectUrl() adding custom header option 'Accept-Encoding: identity' 2021-03-23 18:34:19.279 T:991 DEBUG : CurlFile::Open(0xad817620) http://192.168.1.189:8866/service?method=recording.lastupdated&sid=9120575bebe94fcd97b658aa3600b03a 2021-03-23 18:34:19.332 T:991 DEBUG : AddOnLog: pvr.nextpvr: DoMethodRequest recording.lastupdated 0 107 54 2021-03-23 18:34:19.423 T:936 DEBUG : GetRecentlyAddedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM album WHERE strAlbum != '' ORDER BY dateAdded DESC LIMIT 10) AS recentalbums JOIN albumview ON albumview.idAlbum = recentalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY dateAdded DESC, albumview.idAlbum desc, albumartistview.iOrder 2021-03-23 18:34:19.470 T:936 DEBUG : CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update 2021-03-23 18:34:19.470 T:936 DEBUG : connect replacing configured host mysql-pi01 with resolved host 192.168.1.177 2021-03-23 18:34:19.687 T:992 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:34:19.687 T:992 DEBUG : DoWork - trying to extract thumb from video file smb://nas01/Public/Shared Videos/Megamind/Megamind The Button of Doom.mkv 2021-03-23 18:34:19.705 T:992 DEBUG : CSMBFile::Open - opened smb://nas01/Public/Shared Videos/Megamind/Megamind The Button of Doom.mkv, fd=10000 2021-03-23 18:34:19.768 T:992 DEBUG : Open - probing detected format [matroska,webm] 2021-03-23 18:34:19.817 T:992 DEBUG : Open - avformat_find_stream_info starting 2021-03-23 18:34:19.859 T:992 DEBUG : Open - av_find_stream_info finished 2021-03-23 18:34:19.859 T:992 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 0 2021-03-23 18:34:19.859 T:992 DEBUG : CDVDDemuxFFmpeg::AddStream ID: 1 2021-03-23 18:34:19.859 T:992 DEBUG : ScanForExternalSubtitles: Searching for subtitles... 2021-03-23 18:34:19.890 T:992 DEBUG : ScanForExternalSubtitles: END (total time: 30 ms) 2021-03-23 18:34:19.890 T:992 INFO : CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 2021-03-23 18:34:19.890 T:992 DEBUG : CDVDVideoCodecFFmpeg - Updated codec: ff-h264 2021-03-23 18:34:19.890 T:992 DEBUG : ExtractThumb - seeking to pos 316371ms (total: 949114ms) in smb://nas01/Public/Shared Videos/Megamind/Megamind The Button of Doom.mkv 2021-03-23 18:34:19.916 T:992 DEBUG : SeekTime - seek ended up on time 315767 2021-03-23 18:34:20.113 T:936 DEBUG : CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update 2021-03-23 18:34:20.113 T:936 DEBUG : connect replacing configured host mysql-pi01 with resolved host 192.168.1.177 2021-03-23 18:34:20.146 T:992 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:34:20.146 T:992 DEBUG : cached image 'special://masterprofile/Thumbnails/6/6c0d050e.jpg' size 540x303 2021-03-23 18:34:20.167 T:992 DEBUG : ExtractThumb - measured 481 ms to extract thumb from file in 5 packets. 2021-03-23 18:34:20.167 T:992 DEBUG : CSMBFile::Close closing fd 10000 2021-03-23 18:34:20.215 T:992 DEBUG : connect replacing configured host mysql-pi01 with resolved host 192.168.1.177 2021-03-23 18:34:20.299 T:992 INFO : Skipped 1 duplicate messages.. 2021-03-23 18:34:20.299 T:992 DEBUG : Mysql Start transaction 2021-03-23 18:34:20.299 T:992 DEBUG : Mysql execute: DELETE FROM streamdetails WHERE idFile = 1359 2021-03-23 18:34:20.300 T:985 DEBUG : PushCecKeypress - received key a9 duration 0 2021-03-23 18:34:20.300 T:992 DEBUG : Mysql execute: INSERT INTO streamdetails (idFile, iStreamType, strVideoCodec, fVideoAspect, iVideoWidth, iVideoHeight, iVideoDuration, strStereoMode, strVideoLanguage) VALUES (1359,0,'h264',1.777778,1280,720,949,'','') 2021-03-23 18:34:20.303 T:992 DEBUG : Mysql execute: INSERT INTO streamdetails (idFile, iStreamType, strAudioCodec, iAudioChannels, strAudioLanguage) VALUES (1359,1,'aac',6,'') 2021-03-23 18:34:20.304 T:992 DEBUG : Mysql execute: update movie set c11=949 where idFile=1359 and c11='' 2021-03-23 18:34:20.306 T:992 DEBUG : Mysql execute: update episode set c09=949 where idFile=1359 and c09='' 2021-03-23 18:34:20.309 T:992 DEBUG : Mysql execute: update musicvideo set c04=949 where idFile=1359 and c04='' 2021-03-23 18:34:20.315 T:925 DEBUG : HandleKey: 169 (0xa9, obc86) pressed, action is Left 2021-03-23 18:34:20.319 T:992 DEBUG : Mysql commit transaction 2021-03-23 18:34:20.491 T:985 DEBUG : PushCecKeypress - received key a9 duration 191 2021-03-23 18:34:21.221 T:985 DEBUG : PushCecKeypress - received key a7 duration 0 2021-03-23 18:34:21.231 T:925 DEBUG : HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-03-23 18:34:21.486 T:985 DEBUG : PushCecKeypress - received key a7 duration 265 2021-03-23 18:34:21.772 T:985 DEBUG : PushCecKeypress - received key b duration 0 2021-03-23 18:34:21.773 T:925 DEBUG : HandleKey: 11 (0x0b, obc244) pressed, action is Select 2021-03-23 18:34:21.773 T:925 DEBUG : Activating window ID: 10004 2021-03-23 18:34:22.028 T:925 DEBUG : ------ Window Deinit (Home.xml) ------ 2021-03-23 18:34:22.029 T:925 DEBUG : FreeVisualisation() done 2021-03-23 18:34:22.065 T:925 DEBUG : ------ Window Init (Settings.xml) ------ 2021-03-23 18:34:22.075 T:936 DEBUG : ffmpeg[0x15fd7e8X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format. 2021-03-23 18:34:22.176 T:936 DEBUG : DoWork - took 104 ms to load special://skin/backgrounds/settings.jpg 2021-03-23 18:34:22.181 T:985 DEBUG : PushCecKeypress - received key b duration 408 2021-03-23 18:34:23.143 T:985 DEBUG : PushCecKeypress - received key b duration 0 2021-03-23 18:34:23.144 T:925 DEBUG : HandleKey: 11 (0x0b, obc244) pressed, action is Select 2021-03-23 18:34:23.144 T:925 DEBUG : Activating window ID: 10016 2021-03-23 18:34:23.402 T:925 DEBUG : ------ Window Deinit (Settings.xml) ------ 2021-03-23 18:34:23.402 T:925 DEBUG : FreeVisualisation() done 2021-03-23 18:34:23.440 T:925 DEBUG : ------ Window Init (SettingsCategory.xml) ------ 2021-03-23 18:34:23.576 T:985 DEBUG : PushCecKeypress - received key b duration 433 2021-03-23 18:34:25.043 T:985 DEBUG : PushCecKeypress - received key a6 duration 0 2021-03-23 18:34:25.065 T:925 DEBUG : HandleKey: 166 (0xa6, obc89) pressed, action is Up 2021-03-23 18:34:25.272 T:985 DEBUG : PushCecKeypress - received key a6 duration 229 2021-03-23 18:34:25.656 T:985 DEBUG : PushCecKeypress - received key b duration 0 2021-03-23 18:34:25.662 T:925 DEBUG : HandleKey: 11 (0x0b, obc244) pressed, action is Select 2021-03-23 18:34:26.067 T:985 DEBUG : PushCecKeypress - received key b duration 412 2021-03-23 18:34:26.275 T:985 DEBUG : PushCecKeypress - received key a8 duration 0 2021-03-23 18:34:26.315 T:925 DEBUG : HandleKey: 168 (0xa8, obc87) pressed, action is Right 2021-03-23 18:34:26.561 T:985 DEBUG : PushCecKeypress - received key a8 duration 286 2021-03-23 18:34:26.941 T:985 DEBUG : PushCecKeypress - received key b duration 0 2021-03-23 18:34:26.981 T:925 DEBUG : HandleKey: 11 (0x0b, obc244) pressed, action is Select 2021-03-23 18:34:26.982 T:925 INFO : Disabled debug logging due to GUI setting. Level 0. 2021-03-23 18:34:26.983 T:925 INFO : Log level changed to "INFO" 2021-03-23 18:34:30.278 T:993 INFO : CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 2021-03-23 18:36:01.501 T:925 INFO : Samba is idle. Closing the remaining connections