============================================================= Note: reboot ============================================================= 09:28:03.846 T:4114313040 NOTICE: special://profile/ is mapped to: special://masterprofile/ 09:28:03.846 T:4114313040 NOTICE: ----------------------------------------------------------------------- 09:28:03.846 T:4114313040 NOTICE: Starting Kodi (17.0 Git:a10c504). Platform: Linux ARM 32-bit 09:28:03.847 T:4114313040 NOTICE: Using Release Kodi x32 build 09:28:03.847 T:4114313040 NOTICE: Kodi compiled Feb 3 2017 by GCC 6.2.0 for Linux ARM 32-bit version 3.14.29 (200221) 09:28:03.847 T:4114313040 NOTICE: Running on LibreELEC (wrxtasy-raybuntu-media_build) - Version: 8.0, kernel: Linux ARM 64-bit version 3.14.29 09:28:03.847 T:4114313040 NOTICE: FFmpeg version/source: ffmpeg-3.1-kodi 09:28:03.847 T:4114313040 NOTICE: Host CPU: AArch64 Processor rev 4 (aarch64), 4 cores available 09:28:03.847 T:4114313040 NOTICE: ARM Features: Neon enabled 09:28:03.847 T:4114313040 NOTICE: special://xbmc/ is mapped to: /usr/share/kodi/ 09:28:03.847 T:4114313040 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/kodi 09:28:03.847 T:4114313040 NOTICE: special://xbmcbinaddons/ is mapped to: /usr/lib/kodi/addons 09:28:03.847 T:4114313040 NOTICE: special://masterprofile/ is mapped to: /storage/.kodi/userdata 09:28:03.847 T:4114313040 NOTICE: special://envhome/ is mapped to: /storage 09:28:03.847 T:4114313040 NOTICE: special://home/ is mapped to: /storage/.kodi 09:28:03.847 T:4114313040 NOTICE: special://temp/ is mapped to: /storage/.kodi/temp 09:28:03.847 T:4114313040 NOTICE: special://logpath/ is mapped to: /storage/.kodi/temp 09:28:03.847 T:4114313040 NOTICE: The executable running is: /usr/lib/kodi/kodi.bin 09:28:03.847 T:4114313040 NOTICE: Local hostname: LibreELEC 09:28:03.847 T:4114313040 NOTICE: Log File is located: /storage/.kodi/temp//kodi.log 09:28:03.847 T:4114313040 NOTICE: ----------------------------------------------------------------------- 09:28:03.888 T:4114313040 ERROR: DBus: Error org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files 09:28:03.895 T:4114313040 NOTICE: load settings... 09:28:03.896 T:4114313040 NOTICE: AML device detected 09:28:04.025 T:4114313040 NOTICE: Found 2 Lists of Devices 09:28:04.025 T:4114313040 NOTICE: Enumerated ALSA devices: 09:28:04.025 T:4114313040 NOTICE: Device 1 09:28:04.025 T:4114313040 NOTICE: m_deviceName : @:CARD=AMLM8AUDIO,DEV=0 09:28:04.025 T:4114313040 NOTICE: m_displayName : AML-M8AUDIO 09:28:04.025 T:4114313040 NOTICE: m_displayNameExtra: PCM 09:28:04.025 T:4114313040 NOTICE: m_deviceType : AE_DEVTYPE_PCM 09:28:04.025 T:4114313040 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR 09:28:04.025 T:4114313040 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 09:28:04.025 T:4114313040 NOTICE: m_dataFormats : AE_FMT_S32NE 09:28:04.025 T:4114313040 NOTICE: m_streamTypes : No passthrough capabilities 09:28:04.025 T:4114313040 NOTICE: Device 2 09:28:04.025 T:4114313040 NOTICE: m_deviceName : hdmi:CARD=AMLM8AUDIO,DEV=0 09:28:04.025 T:4114313040 NOTICE: m_displayName : AML-M8AUDIO 09:28:04.025 T:4114313040 NOTICE: m_displayNameExtra: HDMI 09:28:04.025 T:4114313040 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 09:28:04.025 T:4114313040 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR 09:28:04.025 T:4114313040 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 09:28:04.025 T:4114313040 NOTICE: m_dataFormats : AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW 09:28:04.025 T:4114313040 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512,STREAM_TYPE_EAC3,STREAM_TYPE_TRUEHD 09:28:04.026 T:4114313040 NOTICE: Enumerated PULSE devices: 09:28:04.026 T:4114313040 NOTICE: Device 1 09:28:04.026 T:4114313040 NOTICE: m_deviceName : Default 09:28:04.026 T:4114313040 NOTICE: m_displayName : Default 09:28:04.026 T:4114313040 NOTICE: m_displayNameExtra: Bluetooth Audio (PULSEAUDIO) 09:28:04.026 T:4114313040 NOTICE: m_deviceType : AE_DEVTYPE_PCM 09:28:04.026 T:4114313040 NOTICE: m_channels : FL,FR 09:28:04.026 T:4114313040 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000 09:28:04.026 T:4114313040 NOTICE: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT 09:28:04.026 T:4114313040 NOTICE: m_streamTypes : No passthrough capabilities 09:28:04.140 T:4114313040 NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml 09:28:04.140 T:4114313040 NOTICE: Contents of special://xbmc/system/advancedsettings.xml are... false 30 09:28:04.142 T:4114313040 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml) 09:28:04.142 T:4114313040 NOTICE: Default Video Player: VideoPlayer 09:28:04.142 T:4114313040 NOTICE: Default Audio Player: paplayer 09:28:04.142 T:4114313040 NOTICE: Enabled debug logging due to GUI setting (2) 09:28:04.142 T:4114313040 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM" 09:28:04.142 T:4114313040 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml 09:28:04.146 T:4114313040 DEBUG: CMediaSourceSettings: tag is missing or sources.xml is malformed 09:28:04.147 T:4114313040 DEBUG: CMediaSourceSettings: tag is missing or sources.xml is malformed 09:28:04.147 T:4114313040 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 09:28:04.147 T:4114313040 DEBUG: CPlayerCoreConfig::: created player VideoPlayer 09:28:04.147 T:4114313040 DEBUG: CPlayerCoreConfig::: created player PAPlayer 09:28:04.147 T:4114313040 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules 09:28:04.147 T:4114313040 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mms/udp 09:28:04.147 T:4114313040 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout 09:28:04.147 T:4114313040 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp 09:28:04.147 T:4114313040 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp 09:28:04.147 T:4114313040 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams 09:28:04.147 T:4114313040 DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp 09:28:04.147 T:4114313040 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2 09:28:04.147 T:4114313040 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd 09:28:04.147 T:4114313040 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage 09:28:04.148 T:4114313040 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf 09:28:04.148 T:4114313040 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv 09:28:04.148 T:4114313040 DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio 09:28:04.148 T:4114313040 NOTICE: Loaded playercorefactory configuration 09:28:04.148 T:4114313040 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml. 09:28:04.148 T:4114313040 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping. 09:28:04.148 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:04.172 T:4114313040 DEBUG: Previous line repeats 7 times. 09:28:04.172 T:4114313040 INFO: creating subdirectories 09:28:04.172 T:4114313040 INFO: userdata folder: special://masterprofile/ 09:28:04.172 T:4114313040 INFO: recording folder: 09:28:04.172 T:4114313040 INFO: screenshots folder: /storage/screenshots/ 09:28:04.172 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:04.266 T:4114313040 DEBUG: Previous line repeats 51 times. 09:28:04.267 T:4114313040 NOTICE: Running database version Addons27 09:28:04.267 T:4114313040 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-arm.so) 09:28:04.267 T:4114313040 DEBUG: Loading: /usr/lib/kodi/system/libcpluff-arm.so 09:28:04.347 T:4114313040 INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/lib/kodi/addons: No such file or directory' 09:28:04.347 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in weather.openweathermap.extended has been installed.' 09:28:04.347 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.' 09:28:04.347 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been installed.' 09:28:04.347 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in resource.images.weathericons.default has been installed.' 09:28:04.347 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in xbmc.audioencoder has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in script.openweathermap.maps has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in kodi.audiodecoder has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in kodi.peripheral has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in kodi.guilib has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in peripheral.joystick has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in kodi.adsp has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.wma has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in game.controller.default has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in service.libreelec.settings has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in kodi.game has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in service.tvheadend42 has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in service.subtitles.tv4user has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in kodi.resource has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in os.openelec.tv has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in repository.kodi.game has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in os.libreelec.tv has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in script.module.requests has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in kodi.inputstream has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in repository.libreelec.tv has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in service.tvh.manager has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.aac has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.' 09:28:04.348 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in resource.language.en_gb has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in xbmc.webinterface has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.kodi has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in resource.language.de_de has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in repository.rbrepo has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in pvr.hts has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Plug-in skin.estuary has been installed.' 09:28:04.349 T:4114313040 DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.' 09:28:04.372 T:4026524576 DEBUG: Thread JobWorker start, auto delete: true 09:28:04.381 T:4114313040 NOTICE: ADDONS: Using repository repository.kodi.game 09:28:04.381 T:4114313040 NOTICE: ADDONS: Using repository repository.libreelec.tv 09:28:04.381 T:4114313040 NOTICE: ADDONS: Using repository repository.xbmc.org 09:28:04.381 T:4114313040 NOTICE: ADDONS: Using repository repository.rbrepo 09:28:04.386 T:4092289952 DEBUG: Thread ActiveAE start, auto delete: false 09:28:04.386 T:4082103200 DEBUG: Thread AESink start, auto delete: false 09:28:04.387 T:4082103200 INFO: CActiveAESink::OpenSink - initialize sink 09:28:04.387 T:4082103200 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@:CARD=AMLM8AUDIO,DEV=0 09:28:04.387 T:4082103200 INFO: CAESinkALSA::Initialize - Attempting to open device "@:CARD=AMLM8AUDIO,DEV=0" 09:28:04.392 T:4082103200 INFO: CAESinkALSA - Unable to open device "sysdefault:CARD=AMLM8AUDIO" for playback 09:28:04.394 T:4082103200 INFO: CAESinkALSA - Unable to open device "default:CARD=AMLM8AUDIO" for playback 09:28:04.395 T:4082103200 INFO: CAESinkALSA::Initialize - Opened device "front:CARD=AMLM8AUDIO,DEV=0" 09:28:04.395 T:4082103200 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 09:28:04.396 T:4082103200 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 09:28:04.396 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 09:28:04.400 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192 09:28:04.400 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 186 ms 09:28:04.400 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 09:28:04.400 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 09:28:04.400 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: none) 09:28:04.400 T:4082103200 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 09:28:04.401 T:4082103200 DEBUG: Output Device : AML-M8AUDIO 09:28:04.401 T:4082103200 DEBUG: Sample Rate : 44100 09:28:04.401 T:4082103200 DEBUG: Sample Format : AE_FMT_S32NE 09:28:04.401 T:4082103200 DEBUG: Channel Count : 2 09:28:04.401 T:4082103200 DEBUG: Channel Layout: FL,FR 09:28:04.401 T:4082103200 DEBUG: Frames : 2048 09:28:04.401 T:4082103200 DEBUG: Frame Size : 8 09:28:04.405 T:4071617440 DEBUG: Thread RemoteControl start, auto delete: false 09:28:04.405 T:4071617440 INFO: LIRC Process: using: /run/lirc/lircd 09:28:04.405 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:04.405 T:4071617440 DEBUG: Previous line repeats 1 times. 09:28:04.405 T:4071617440 INFO: LIRC Connect: successfully started 09:28:04.405 T:4071617440 DEBUG: Thread RemoteControl 4071617440 terminating 09:28:04.405 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:04.410 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:04.410 T:4114313040 INFO: CKeyboardLayoutManager: loading keyboard layouts from special://xbmc/system/keyboardlayouts... 09:28:04.415 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Arabic QWERTY" successfully loaded 09:28:04.416 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian ЯВЕРТЪ" successfully loaded 09:28:04.417 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian АБВ" successfully loaded 09:28:04.418 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BasePY" successfully loaded 09:28:04.418 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BaiduPY" successfully loaded 09:28:04.419 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Croatian QWERTY" successfully loaded 09:28:04.419 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Czech QWERTZ" successfully loaded 09:28:04.420 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Danish QWERTY" successfully loaded 09:28:04.421 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "English QWERTY" successfully loaded 09:28:04.421 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "English AZERTY" successfully loaded 09:28:04.422 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "English ABC" successfully loaded 09:28:04.422 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "French AZERTY" successfully loaded 09:28:04.423 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "German QWERTZ" successfully loaded 09:28:04.424 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "German ABC" successfully loaded 09:28:04.424 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Greek QWERTY" successfully loaded 09:28:04.425 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew QWERTY" successfully loaded 09:28:04.425 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew ABC" successfully loaded 09:28:04.426 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Hungarian QWERTZ" successfully loaded 09:28:04.427 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Italian QWERTY" successfully loaded 09:28:04.427 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Korean ㄱㄴㄷ" successfully loaded 09:28:04.428 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian AZERTY" successfully loaded 09:28:04.429 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian QWERTY" successfully loaded 09:28:04.429 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Norwegian QWERTY" successfully loaded 09:28:04.430 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Polish QWERTY" successfully loaded 09:28:04.431 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Portuguese (Portugal) QWERTY" successfully loaded 09:28:04.432 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Romanian QWERTY" successfully loaded 09:28:04.432 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian ЙЦУКЕН" successfully loaded 09:28:04.433 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian АБВ" successfully loaded 09:28:04.433 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Silesian QWERTY" successfully loaded 09:28:04.434 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTZ" successfully loaded 09:28:04.435 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTY" successfully loaded 09:28:04.435 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak ABC" successfully loaded 09:28:04.436 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Spanish QWERTY" successfully loaded 09:28:04.437 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Swedish QWERTY" successfully loaded 09:28:04.438 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Turkish QWERTY" successfully loaded 09:28:04.439 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian ЙЦУКЕН" successfully loaded 09:28:04.439 T:4114313040 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian АБВ" successfully loaded 09:28:04.440 T:4114313040 DEBUG: UDisks: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UDisks was not provided by any .service files 09:28:04.441 T:4114313040 DEBUG: DeviceKit.Disks: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.DeviceKit.Disks was not provided by any .service files 09:28:04.459 T:4114313040 DEBUG: Selected UDev as storage provider 09:28:04.460 T:4114313040 ERROR: AML: no rw on /sys/class/ppmgr/ppmgr_3d_mode 09:28:04.490 T:4114313040 NOTICE: InitWindowSystem: Using EGL Implementation: amlogic 09:28:04.524 T:4114313040 NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 60.000000 Hz 09:28:04.524 T:4114313040 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080i @ 60.000000 Hz 09:28:04.524 T:4114313040 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 60.000000 Hz 09:28:04.524 T:4114313040 NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 50.000000 Hz 09:28:04.524 T:4114313040 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080i @ 50.000000 Hz 09:28:04.524 T:4114313040 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 30.000000 Hz 09:28:04.525 T:4114313040 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 50.000000 Hz 09:28:04.525 T:4114313040 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 24.000000 Hz 09:28:04.525 T:4114313040 NOTICE: Found (1920x1080@60.000000) at 18, setting to RES_DESKTOP at 16 09:28:04.525 T:4114313040 NOTICE: Checking resolution 16 09:28:04.557 T:4114313040 DEBUG: CreateWindow: Created surface of size 1920x1080 09:28:04.573 T:4114313040 NOTICE: GL_VENDOR = ARM 09:28:04.573 T:4114313040 NOTICE: GL_RENDERER = Mali-450 MP 09:28:04.573 T:4114313040 NOTICE: GL_VERSION = OpenGL ES 2.0 09:28:04.573 T:4114313040 NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 1.00 09:28:04.573 T:4114313040 DEBUG: GLES: Extension Support Test - GL_NVX_gpu_memory_info NO 09:28:04.573 T:4114313040 NOTICE: GL_EXTENSIONS = GL_OES_texture_npot GL_OES_vertex_array_object GL_OES_compressed_ETC1_RGB8_texture GL_EXT_compressed_ETC1_RGB8_sub_texture GL_OES_standard_derivatives GL_OES_EGL_image GL_OES_depth24 GL_ARM_rgba8 GL_ARM_mali_shader_binary GL_OES_depth_texture GL_OES_packed_depth_stencil GL_EXT_texture_format_BGRA8888 GL_OES_vertex_half_float GL_EXT_blend_minmax GL_OES_EGL_image_external GL_OES_EGL_sync GL_OES_rgb8_rgba8 GL_EXT_multisampled_render_to_texture GL_EXT_discard_framebuffer GL_OES_get_program_binary GL_ARM_mali_program_binary GL_EXT_shader_texture_lod GL_EXT_robustness GL_OES_depth_texture_cube_map GL_KHR_debug GL_ARM_shader_framebuffer_fetch GL_ARM_shader_framebuffer_fetch_depth_stencil GL_OES_mapbuffer 09:28:04.574 T:4114313040 DEBUG: GLES: Extension Support Test - GL_EXT_texture_format_BGRA8888 YES 09:28:04.574 T:4114313040 DEBUG: GLES: Extension Support Test - GL_IMG_texture_format_BGRA8888 NO 09:28:04.574 T:4114313040 DEBUG: GLES: Extension Support Test - GL_APPLE_texture_format_BGRA8888 NO 09:28:04.588 T:4114313040 DEBUG: GL: Vertex Shader compilation log: 09:28:04.588 T:4114313040 DEBUG: GL: Vertex Shader compiled successfully 09:28:04.589 T:4114313040 DEBUG: GL: Pixel Shader compilation log: 09:28:04.589 T:4114313040 DEBUG: GL: Fragment Shader compiled successfully 09:28:04.590 T:4114313040 DEBUG: GUI Shader [guishader_frag_default.glsl]- Initialise successful : 0x16391f0 09:28:04.598 T:4114313040 DEBUG: GL: Vertex Shader compilation log: 09:28:04.599 T:4114313040 DEBUG: GL: Vertex Shader compiled successfully 09:28:04.599 T:4114313040 DEBUG: GL: Pixel Shader compilation log: 09:28:04.599 T:4114313040 DEBUG: GL: Fragment Shader compiled successfully 09:28:04.600 T:4114313040 DEBUG: GUI Shader [guishader_frag_texture.glsl]- Initialise successful : 0x16499b0 09:28:04.612 T:4114313040 DEBUG: GL: Vertex Shader compilation log: 09:28:04.612 T:4114313040 DEBUG: GL: Vertex Shader compiled successfully 09:28:04.613 T:4114313040 DEBUG: GL: Pixel Shader compilation log: 09:28:04.613 T:4114313040 DEBUG: GL: Fragment Shader compiled successfully 09:28:04.614 T:4114313040 DEBUG: GUI Shader [guishader_frag_multi.glsl]- Initialise successful : 0x163f2d8 09:28:04.624 T:4114313040 DEBUG: GL: Vertex Shader compilation log: 09:28:04.624 T:4114313040 DEBUG: GL: Vertex Shader compiled successfully 09:28:04.625 T:4114313040 DEBUG: GL: Pixel Shader compilation log: 09:28:04.626 T:4114313040 DEBUG: GL: Fragment Shader compiled successfully 09:28:04.626 T:4114313040 DEBUG: GUI Shader [guishader_frag_fonts.glsl]- Initialise successful : 0x1694178 09:28:04.637 T:4114313040 DEBUG: GL: Vertex Shader compilation log: 09:28:04.637 T:4114313040 DEBUG: GL: Vertex Shader compiled successfully 09:28:04.638 T:4114313040 DEBUG: GL: Pixel Shader compilation log: 09:28:04.638 T:4114313040 DEBUG: GL: Fragment Shader compiled successfully 09:28:04.639 T:4114313040 DEBUG: GUI Shader [guishader_frag_texture_noblend.glsl]- Initialise successful : 0x1641290 09:28:04.650 T:4114313040 DEBUG: GL: Vertex Shader compilation log: 09:28:04.650 T:4114313040 DEBUG: GL: Vertex Shader compiled successfully 09:28:04.651 T:4114313040 DEBUG: GL: Pixel Shader compilation log: 09:28:04.651 T:4114313040 DEBUG: GL: Fragment Shader compiled successfully 09:28:04.652 T:4114313040 DEBUG: GUI Shader [guishader_frag_multi_blendcolor.glsl]- Initialise successful : 0x16a94e0 09:28:04.663 T:4114313040 DEBUG: GL: Vertex Shader compilation log: 09:28:04.663 T:4114313040 DEBUG: GL: Vertex Shader compiled successfully 09:28:04.664 T:4114313040 DEBUG: GL: Pixel Shader compilation log: 09:28:04.664 T:4114313040 DEBUG: GL: Fragment Shader compiled successfully 09:28:04.665 T:4114313040 DEBUG: GUI Shader [guishader_frag_rgba.glsl]- Initialise successful : 0x16ac9a8 09:28:04.665 T:4114313040 DEBUG: GLES: Extension Support Test - GL_OES_EGL_image_external YES 09:28:04.675 T:4114313040 DEBUG: GL: Vertex Shader compilation log: 09:28:04.676 T:4114313040 DEBUG: GL: Vertex Shader compiled successfully 09:28:04.677 T:4114313040 DEBUG: GL: Pixel Shader compilation log: 09:28:04.677 T:4114313040 DEBUG: GL: Fragment Shader compiled successfully 09:28:04.677 T:4114313040 DEBUG: GUI Shader [guishader_frag_rgba_oes.glsl]- Initialise successful : 0x16b08a8 09:28:04.686 T:4114313040 DEBUG: GL: Vertex Shader compilation log: 09:28:04.687 T:4114313040 DEBUG: GL: Vertex Shader compiled successfully 09:28:04.687 T:4114313040 DEBUG: GL: Pixel Shader compilation log: 09:28:04.687 T:4114313040 DEBUG: GL: Fragment Shader compiled successfully 09:28:04.688 T:4114313040 DEBUG: GUI Shader [guishader_frag_rgba_blendcolor.glsl]- Initialise successful : 0x16b6180 09:28:04.697 T:4114313040 DEBUG: GL: Vertex Shader compilation log: 09:28:04.697 T:4114313040 DEBUG: GL: Vertex Shader compiled successfully 09:28:04.699 T:4114313040 DEBUG: GL: Pixel Shader compilation log: 09:28:04.699 T:4114313040 DEBUG: GL: Fragment Shader compiled successfully 09:28:04.699 T:4114313040 DEBUG: GUI Shader [guishader_frag_rgba_bob.glsl]- Initialise successful : 0x16bab68 09:28:04.699 T:4114313040 DEBUG: GLES: Extension Support Test - GL_OES_EGL_image_external YES 09:28:04.708 T:4114313040 DEBUG: GL: Vertex Shader compilation log: 09:28:04.708 T:4114313040 DEBUG: GL: Vertex Shader compiled successfully 09:28:04.710 T:4114313040 DEBUG: GL: Pixel Shader compilation log: 09:28:04.710 T:4114313040 DEBUG: GL: Fragment Shader compiled successfully 09:28:04.711 T:4114313040 DEBUG: GUI Shader [guishader_frag_rgba_bob_oes.glsl]- Initialise successful : 0x16c0870 09:28:04.711 T:4114313040 DEBUG: CWinSystemEGL::CreateNewWindow: No need to create a new window 09:28:04.711 T:4114313040 INFO: GLES: Maximum texture width: 4096 09:28:04.967 T:4114313040 INFO: GLES: Enabling VSYNC 09:28:04.967 T:4114313040 INFO: GLES: Selected vsync mode 10 09:28:04.969 T:4114313040 INFO: load keymapping 09:28:04.970 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:04.973 T:4114313040 DEBUG: Previous line repeats 3 times. 09:28:04.973 T:4114313040 INFO: Loading special://xbmc/system/keymaps/appcommand.xml 09:28:04.974 T:4114313040 INFO: Loading special://xbmc/system/keymaps/customcontroller.AppleRemote.xml 09:28:04.976 T:4114313040 INFO: Loading special://xbmc/system/keymaps/customcontroller.Harmony.xml 09:28:04.981 T:4114313040 INFO: Loading special://xbmc/system/keymaps/gamepad.xml 09:28:04.985 T:4114313040 INFO: Loading special://xbmc/system/keymaps/joystick.xml 09:28:04.989 T:4114313040 INFO: Loading special://xbmc/system/keymaps/keyboard.xml 09:28:05.002 T:4114313040 INFO: Loading special://xbmc/system/keymaps/mouse.xml 09:28:05.004 T:4114313040 INFO: Loading special://xbmc/system/keymaps/remote.xml 09:28:05.014 T:4114313040 INFO: Loading special://xbmc/system/keymaps/touchscreen.xml 09:28:05.016 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.021 T:4114313040 DEBUG: Previous line repeats 7 times. 09:28:05.021 T:4114313040 INFO: Loading special://xbmc/system/Lircmap.xml 09:28:05.025 T:4114313040 INFO: * Adding remote mapping for device 'mceusb' 09:28:05.025 T:4114313040 INFO: * Adding remote mapping for device 'XboxDVDDongle' 09:28:05.026 T:4114313040 INFO: * Adding remote mapping for device 'Microsoft_Xbox' 09:28:05.026 T:4114313040 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote' 09:28:05.026 T:4114313040 INFO: * Adding remote mapping for device 'anysee' 09:28:05.026 T:4114313040 INFO: * Adding remote mapping for device 'iMON-PAD' 09:28:05.026 T:4114313040 INFO: * Adding remote mapping for device 'Antec_Veris_RM200' 09:28:05.026 T:4114313040 INFO: * Adding remote mapping for device 'MCE_via_iMON' 09:28:05.026 T:4114313040 INFO: * Adding remote mapping for device 'TwinHanRemote' 09:28:05.026 T:4114313040 INFO: * Adding remote mapping for device 'linux-input-layer' 09:28:05.026 T:4114313040 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote' 09:28:05.026 T:4114313040 INFO: * Adding remote mapping for device 'mediacenter' 09:28:05.026 T:4114313040 INFO: * Adding remote mapping for device 'devinput' 09:28:05.027 T:4114313040 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping 09:28:05.027 T:4114313040 INFO: GUI format 1920x1080, Display 1920x1080 @ 60.00 - Full Screen 09:28:05.027 T:4114313040 DEBUG: guilib: Fill viewport on change for solving rendering passes 09:28:05.027 T:4114313040 INFO: CLangInfo: loading resource.language.de_de language information... 09:28:05.033 T:4114313040 DEBUG: trying to set locale to de_BE.UTF-8 09:28:05.038 T:4114313040 INFO: global locale set to C 09:28:05.039 T:4114313040 INFO: CLangInfo: loading resource.language.de_de language strings... 09:28:05.039 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.098 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:05.099 T:4114313040 DEBUG: LocalizeStrings: loaded 3768 strings from file resource://resource.language.de_de/strings.po 09:28:05.099 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.123 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:05.123 T:4114313040 DEBUG: POParser: id:20433 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead 09:28:05.124 T:4114313040 DEBUG: POParser: id:24096 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead 09:28:05.127 T:4114313040 DEBUG: POParser: id:36178 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead 09:28:05.127 T:4114313040 DEBUG: POParser: id:36180 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead 09:28:05.130 T:4114313040 DEBUG: POParser: id:38040 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead 09:28:05.130 T:4114313040 DEBUG: POParser: id:38041 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead 09:28:05.131 T:4114313040 DEBUG: LocalizeStrings: loaded 19 strings from file resource://resource.language.en_gb/strings.po 09:28:05.140 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.142 T:4114313040 DEBUG: Previous line repeats 3 times. 09:28:05.142 T:4114313040 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.xbmc.builtin.aac/resources/language/English/strings.po 09:28:05.142 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.143 T:4114313040 DEBUG: Previous line repeats 3 times. 09:28:05.143 T:4114313040 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.xbmc.builtin.wma/resources/language/English/strings.po 09:28:05.143 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.145 T:4114313040 DEBUG: Previous line repeats 2 times. 09:28:05.145 T:4114313040 DEBUG: LocalizeStrings: loaded 18 strings from file /usr/share/kodi/addons/game.controller.default/resources/language/resource.language.en_gb/strings.po 09:28:05.150 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.151 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:05.151 T:4114313040 DEBUG: LocalizeStrings: loaded 11 strings from file /storage/.kodi/addons/metadata.album.universal/resources/language/German/strings.po 09:28:05.152 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.153 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:05.153 T:4114313040 DEBUG: LocalizeStrings: loaded 2 strings from file /storage/.kodi/addons/metadata.album.universal/resources/language/English/strings.po 09:28:05.155 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.157 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:05.157 T:4114313040 DEBUG: LocalizeStrings: loaded 23 strings from file /storage/.kodi/addons/metadata.artists.universal/resources/language/German/strings.po 09:28:05.157 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.159 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:05.159 T:4114313040 DEBUG: LocalizeStrings: loaded 3 strings from file /storage/.kodi/addons/metadata.artists.universal/resources/language/English/strings.po 09:28:05.159 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.172 T:4114313040 DEBUG: Previous line repeats 33 times. 09:28:05.172 T:4114313040 DEBUG: LocalizeStrings: loaded 7 strings from file /storage/.kodi/addons/metadata.themoviedb.org/resources/language/German/strings.po 09:28:05.172 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.174 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:05.174 T:4114313040 DEBUG: LocalizeStrings: loaded 0 strings from file /storage/.kodi/addons/metadata.themoviedb.org/resources/language/English/strings.po 09:28:05.175 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.177 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:05.177 T:4114313040 DEBUG: LocalizeStrings: loaded 5 strings from file /storage/.kodi/addons/metadata.tvdb.com/resources/language/German/strings.po 09:28:05.177 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.178 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:05.178 T:4114313040 DEBUG: LocalizeStrings: loaded 2 strings from file /storage/.kodi/addons/metadata.tvdb.com/resources/language/English/strings.po 09:28:05.179 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.186 T:4114313040 DEBUG: Previous line repeats 32 times. 09:28:05.186 T:4114313040 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/screensaver.xbmc.builtin.dim/resources/language/resource.language.de_de/strings.po 09:28:05.187 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.187 T:4114313040 DEBUG: LocalizeStrings: loaded 0 strings from file /usr/share/kodi/addons/screensaver.xbmc.builtin.dim/resources/language/resource.language.en_gb/strings.po 09:28:05.187 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.191 T:4114313040 DEBUG: Previous line repeats 5 times. 09:28:05.191 T:4114313040 DEBUG: LocalizeStrings: loaded 183 strings from file /usr/share/kodi/addons/service.libreelec.settings/resources/language/German/strings.po 09:28:05.192 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.193 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:05.193 T:4114313040 DEBUG: POParser: id:609 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead 09:28:05.193 T:4114313040 DEBUG: POParser: id:610 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead 09:28:05.194 T:4114313040 DEBUG: POParser: id:32187 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead 09:28:05.194 T:4114313040 DEBUG: LocalizeStrings: loaded 21 strings from file /usr/share/kodi/addons/service.libreelec.settings/resources/language/English/strings.po 09:28:05.195 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.204 T:4114313040 DEBUG: Previous line repeats 12 times. 09:28:05.204 T:4114313040 DEBUG: LocalizeStrings: loaded 74 strings from file /storage/.kodi/addons/pvr.hts/resources/language/resource.language.de_de/strings.po 09:28:05.205 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.206 T:4114313040 DEBUG: LocalizeStrings: loaded 0 strings from file /storage/.kodi/addons/pvr.hts/resources/language/resource.language.en_gb/strings.po 09:28:05.208 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.210 T:4114313040 DEBUG: Previous line repeats 3 times. 09:28:05.210 T:4114313040 DEBUG: LocalizeStrings: loaded 35 strings from file /storage/.kodi/addons/service.tvheadend42/resources/language/English/strings.xml 09:28:05.211 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.212 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:05.212 T:4114313040 DEBUG: LocalizeStrings: loaded 4 strings from file /storage/.kodi/addons/service.subtitles.tv4user/resources/language/German/strings.xml 09:28:05.213 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.214 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:05.214 T:4114313040 DEBUG: LocalizeStrings: loaded 0 strings from file /storage/.kodi/addons/service.subtitles.tv4user/resources/language/English/strings.xml 09:28:05.214 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.219 T:4114313040 DEBUG: Previous line repeats 9 times. 09:28:05.219 T:4114313040 DEBUG: LocalizeStrings: loaded 87 strings from file /storage/.kodi/addons/service.tvh.manager/resources/language/German/strings.po 09:28:05.219 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.222 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:05.222 T:4114313040 DEBUG: LocalizeStrings: loaded 0 strings from file /storage/.kodi/addons/service.tvh.manager/resources/language/English/strings.po 09:28:05.222 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.226 T:4114313040 DEBUG: Previous line repeats 5 times. 09:28:05.226 T:4114313040 DEBUG: LocalizeStrings: loaded 24 strings from file /storage/.kodi/addons/weather.openweathermap.extended/resources/language/German/strings.po 09:28:05.227 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.229 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:05.229 T:4114313040 DEBUG: LocalizeStrings: loaded 82 strings from file /storage/.kodi/addons/weather.openweathermap.extended/resources/language/English/strings.po 09:28:05.230 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.232 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:05.232 T:4114313040 DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid" 09:28:05.232 T:4114313040 DEBUG: LoadMappings - loaded node "CEC Adapter" 09:28:05.232 T:4114313040 DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter" 09:28:05.232 T:4114313040 DEBUG: LoadMappings - loaded node "iMON HID device" 09:28:05.232 T:4114313040 DEBUG: LoadMappings - loaded node "WETEK Play remote" 09:28:05.232 T:4114313040 DEBUG: LoadMappings - loaded node "" 09:28:05.233 T:4114313040 DEBUG: CPeripheralBusUSB - initialised udev monitor 09:28:05.233 T:4114313040 DEBUG: SECTION:LoadDLL(libcec.so.4) 09:28:05.234 T:4114313040 DEBUG: Loading: libcec.so.4 09:28:05.249 T:4114313040 DEBUG: Add-on bus: Registering add-on peripheral.joystick 09:28:05.249 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.249 T:4114313040 DEBUG: PERIPHERAL - CreateAddon - creating peripheral add-on instance 'Joystick Support' 09:28:05.249 T:4114313040 DEBUG: ADDON: Dll Initializing - Joystick Support 09:28:05.249 T:4114313040 DEBUG: SECTION:LoadDLL(/usr/lib/kodi/addons/peripheral.joystick/peripheral.joystick.so.1.2.0) 09:28:05.249 T:4114313040 DEBUG: Loading: /usr/lib/kodi/addons/peripheral.joystick/peripheral.joystick.so.1.2.0 09:28:05.260 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.266 T:4114313040 DEBUG: Previous line repeats 2 times. 09:28:05.266 T:4114313040 DEBUG: AddOnLog: Joystick Support: Loaded 14 joystick families with 97 total joysticks 09:28:05.266 T:4114313040 DEBUG: Calling TransferSettings for: Joystick Support 09:28:05.267 T:4009747360 DEBUG: Thread PeripBusUSBUdev start, auto delete: false 09:28:05.267 T:3999265696 DEBUG: Thread PeripBusCEC start, auto delete: false 09:28:05.267 T:3990877088 DEBUG: Thread PeripBusAddon start, auto delete: false 09:28:05.267 T:4114313040 DEBUG: SECTION:LoadDLL(libcurl.so.4) 09:28:05.269 T:4114313040 DEBUG: Loading: libcurl.so.4 09:28:05.270 T:3982488480 DEBUG: Thread PeripEventScanner start, auto delete: false 09:28:05.278 T:4026524576 NOTICE: Running database version Addons27 09:28:05.278 T:4026524576 DEBUG: Initialize, updating databases... 09:28:05.284 T:4026524576 NOTICE: Running database version ViewModes6 09:28:05.298 T:4026524576 NOTICE: Running database version Textures13 09:28:05.313 T:4026524576 NOTICE: Running database version MyMusic60 09:28:05.338 T:4026524576 NOTICE: Running database version MyVideos107 09:28:05.342 T:4026524576 NOTICE: Running database version TV29 09:28:05.347 T:4026524576 NOTICE: Running database version Epg11 09:28:05.350 T:4026524576 DEBUG: Initialize, updating databases... DONE 09:28:05.353 T:4114313040 NOTICE: start dvd mediatype detection 09:28:05.354 T:4114313040 INFO: DPMS: not supported on this platform 09:28:05.354 T:4027245472 DEBUG: Thread DetectDVDMedia start, auto delete: false 09:28:05.354 T:4027245472 DEBUG: Compiled with libcdio Version 0.94 09:28:05.390 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 09:28:05.391 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 09:28:05.404 T:4114313040 INFO: Unloading old skin ... 09:28:05.410 T:4114313040 INFO: load skin from: /usr/share/kodi/addons/skin.estuary (version: 1.9.10) 09:28:05.410 T:4114313040 INFO: load fonts for skin... 09:28:05.415 T:4114313040 INFO: Loading fonts from /usr/share/kodi/addons/skin.estuary/xml/Font.xml 09:28:05.424 T:3999265696 DEBUG: GetMappingForDevice - device (0000:0000) mapped to CEC Adapter (type = cec) 09:28:05.430 T:3999265696 DEBUG: InitialiseFeature - using libCEC v4.0.1 09:28:05.430 T:3999265696 DEBUG: Initialise - initialised peripheral on 'AOCEC' with 2 features and 0 sub devices 09:28:05.430 T:3961525152 DEBUG: Thread CECAdapter start, auto delete: false 09:28:05.430 T:3999265696 NOTICE: Register - new cec device registered on cec->AOCEC: CEC Adapter (0000:0000) 09:28:05.430 T:3961525152 DEBUG: OpenConnection - opening a connection to the CEC adapter: AOCEC 09:28:05.461 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.463 T:4114313040 DEBUG: LocalizeStrings: loaded 131 strings from file /usr/share/kodi/addons/skin.estuary/language/resource.language.de_de/strings.po 09:28:05.464 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.465 T:4114313040 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/skin.estuary/language/resource.language.en_gb/strings.po 09:28:05.465 T:4114313040 INFO: Loading skin includes from /usr/share/kodi/addons/skin.estuary/xml/Includes.xml 09:28:05.558 T:4114313040 INFO: load new skin... 09:28:05.558 T:4114313040 INFO: Loading user windows, path /usr/share/kodi/addons/skin.estuary/xml 09:28:05.558 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:05.573 T:4114313040 DEBUG: Load Skin XML: 15.37ms 09:28:05.573 T:4114313040 INFO: initialize new skin... 09:28:05.573 T:4114313040 DEBUG: guilib: Fill viewport on change for solving rendering passes 09:28:05.573 T:4114313040 INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT 09:28:05.579 T:4114313040 DEBUG: OpenBundle - Opened bundle /usr/share/kodi/addons/skin.estuary/media/Textures.xbt 09:28:05.581 T:4114313040 INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT 09:28:05.584 T:4114313040 INFO: Loading skin file: DialogNotification.xml, load type: LOAD_ON_GUI_INIT 09:28:05.586 T:4114313040 INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT 09:28:05.598 T:4114313040 INFO: Loading skin file: DialogSettings.xml, load type: LOAD_ON_GUI_INIT 09:28:05.619 T:4114313040 INFO: Previous line repeats 1 times. 09:28:05.619 T:4114313040 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT 09:28:05.622 T:4114313040 INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT 09:28:05.625 T:4114313040 INFO: Loading skin file: DialogSubtitles.xml, load type: LOAD_ON_GUI_INIT 09:28:05.636 T:4114313040 INFO: Loading skin file: DialogPVRChannelsOSD.xml, load type: LOAD_ON_GUI_INIT 09:28:05.643 T:4114313040 INFO: Loading skin file: DialogPVRGuideOSD.xml, load type: LOAD_ON_GUI_INIT 09:28:05.649 T:4114313040 INFO: Loading skin file: VideoOSD.xml, load type: LOAD_ON_GUI_INIT 09:28:05.669 T:4114313040 INFO: Loading resource://resource.uisounds.kodi/sounds.xml 09:28:05.747 T:4114313040 INFO: skin loaded... 09:28:05.747 T:4114313040 DEBUG: Activating window ID: 12997 09:28:05.747 T:4114313040 DEBUG: ------ Window Init () ------ 09:28:05.747 T:4114313040 INFO: load splash image: /usr/share/kodi/media/Splash.png 09:28:05.778 T:4114313040 DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting 09:28:05.778 T:4114313040 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList" 09:28:05.778 T:4114313040 INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting") 09:28:05.779 T:4114313040 INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting 09:28:05.828 T:4114313040 INFO: JSONRPC v8.0.0: Successfully initialized 09:28:05.828 T:4114313040 DEBUG: ADDON: Starting service addons. 09:28:05.831 T:3927970720 DEBUG: Thread LanguageInvoker start, auto delete: false 09:28:05.831 T:3927970720 INFO: initializing python engine. 09:28:05.831 T:4114313040 DEBUG: Activating window ID: 12999 09:28:05.831 T:4114313040 DEBUG: ------ Window Init (Startup.xml) ------ 09:28:05.831 T:4114313040 INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME 09:28:05.832 T:4114313040 DEBUG: Activating window ID: 10000 09:28:05.832 T:4114313040 DEBUG: ------ Window Deinit (Startup.xml) ------ 09:28:05.832 T:4114313040 DEBUG: ------ Window Init (Home.xml) ------ 09:28:05.832 T:4114313040 INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY 09:28:05.867 T:3927970720 DEBUG: CPythonInvoker(0, /storage/.kodi/addons/service.tvheadend42/default.py): start processing 09:28:05.898 T:3927970720 DEBUG: -->Python Interpreter Initialized<-- 09:28:05.898 T:3927970720 DEBUG: CPythonInvoker(0, /storage/.kodi/addons/service.tvheadend42/default.py): the source file to load is "/storage/.kodi/addons/service.tvheadend42/default.py" 09:28:05.898 T:3927970720 DEBUG: CPythonInvoker(0, /storage/.kodi/addons/service.tvheadend42/default.py): setting the Python path to /storage/.kodi/addons/service.tvheadend42:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages/gtk-2.0 09:28:05.898 T:3927970720 DEBUG: CPythonInvoker(0, /storage/.kodi/addons/service.tvheadend42/default.py): entering source directory /storage/.kodi/addons/service.tvheadend42 09:28:05.898 T:3927970720 DEBUG: CPythonInvoker(0, /storage/.kodi/addons/service.tvheadend42/default.py): instantiating addon using automatically obtained id of "service.tvheadend42" dependent on version 2.1.0 of the xbmc.python api 09:28:06.029 T:4114313040 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing.. 09:28:06.030 T:4114313040 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing.. 09:28:06.030 T:4026524576 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:06.030 T:3919582112 DEBUG: Thread JobWorker start, auto delete: true 09:28:06.030 T:4114313040 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing.. 09:28:06.030 T:4114313040 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing.. 09:28:06.030 T:4114313040 DEBUG: CDirectoryProvider[library://video/]: refreshing.. 09:28:06.030 T:4114313040 DEBUG: CDirectoryProvider[sources://video/]: refreshing.. 09:28:06.030 T:3919582112 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:06.030 T:4114313040 DEBUG: CDirectoryProvider[special://videoplaylists/]: refreshing.. 09:28:06.030 T:3911193504 DEBUG: Thread JobWorker start, auto delete: true 09:28:06.030 T:4114313040 DEBUG: CDirectoryProvider[pvr://channels/tv/*?view=lastplayed]: refreshing.. 09:28:06.030 T:4114313040 DEBUG: CDirectoryProvider[pvr://recordings/tv/active?view=flat]: refreshing.. 09:28:06.031 T:4114313040 DEBUG: CDirectoryProvider[pvr://channels/radio/*?view=lastplayed]: refreshing.. 09:28:06.031 T:4114313040 DEBUG: CDirectoryProvider[pvr://recordings/radio/active?view=flat]: refreshing.. 09:28:06.031 T:4114313040 DEBUG: CDirectoryProvider[favourites://]: refreshing.. 09:28:06.031 T:4114313040 DEBUG: CDirectoryProvider[sources://pictures/]: refreshing.. 09:28:06.031 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:06.039 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:06.039 T:4114313040 DEBUG: ContextMenuManager: addon menus reloaded. 09:28:06.039 T:4114313040 INFO: removing tempfiles 09:28:06.039 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:06.040 T:4114313040 DEBUG: Previous line repeats 1 times. 09:28:06.040 T:4114313040 DEBUG: ADDON: Starting service addons. 09:28:06.040 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:06.049 T:3919582112 DEBUG: Previous line repeats 9 times. 09:28:06.049 T:3919582112 DEBUG: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/) 09:28:06.049 T:3919582112 DEBUG: Getting Directory (special://profile/playlists/video) 09:28:06.049 T:3919582112 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:06.050 T:3902804896 DEBUG: Previous line repeats 1 times. 09:28:06.050 T:3902804896 DEBUG: Thread LanguageInvoker start, auto delete: false 09:28:06.053 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:06.053 T:3919582112 DEBUG: Getting Directory (special://profile/playlists/mixed) 09:28:06.053 T:3911193504 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/tv/*?view=lastplayed) 09:28:06.054 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:06.054 T:3911193504 DEBUG: CPVRDirectory::GetDirectory(pvr://recordings/tv/active?view=flat) 09:28:06.054 T:3919582112 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:06.055 T:3911193504 DEBUG: Previous line repeats 2 times. 09:28:06.055 T:3911193504 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/radio/*?view=lastplayed) 09:28:06.055 T:3919582112 DEBUG: CMultiPathDirectory::MergeItems, items = 0 09:28:06.056 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:06.056 T:3911193504 DEBUG: Previous line repeats 1 times. 09:28:06.056 T:3911193504 DEBUG: CPVRDirectory::GetDirectory(pvr://recordings/radio/active?view=flat) 09:28:06.056 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:06.057 T:3919582112 DEBUG: CFavourites::Load - no system favourites found, skipping 09:28:06.059 T:3902804896 INFO: initializing python engine. 09:28:06.059 T:3902804896 DEBUG: CPythonInvoker(1, /usr/share/kodi/addons/service.libreelec.settings/service.py): start processing 09:28:06.061 T:3919582112 WARNING: CreateLoader - unsupported protocol(activatewindow(10701,"pvr) in activatewindow(10701,"pvr://recordings/tv/active/Der%20Vulkan%2c%20der%20die%20Welt%20ver%c3%a4nderte",return).tbn 09:28:06.061 T:3919582112 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update 09:28:06.071 T:4114313040 DEBUG: CRepositoryUpdater: previous update at 06.10.2017 10:21:51, next at 07.10.2017 10:21:51 09:28:06.071 T:4026524576 DEBUG: PVR - Create - creating PVR add-on instance 'Tvheadend HTSP Client' 09:28:06.071 T:4026524576 DEBUG: ADDON: Dll Initializing - Tvheadend HTSP Client 09:28:06.071 T:4114313040 NOTICE: initialize done 09:28:06.071 T:4114313040 NOTICE: Running the application... 09:28:06.071 T:4026524576 DEBUG: SECTION:LoadDLL(/storage/.kodi/addons/pvr.hts/pvr.hts.so.3.4.23) 09:28:06.071 T:4026524576 DEBUG: Loading: /storage/.kodi/addons/pvr.hts/pvr.hts.so.3.4.23 09:28:06.071 T:3894416288 DEBUG: Thread Timer start, auto delete: false 09:28:06.072 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:06.076 T:3919582112 DEBUG: Previous line repeats 5 times. 09:28:06.076 T:3919582112 DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.*, songartistview.* FROM (SELECT idAlbum FROM album ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum = recentalbums.idAlbum JOIN songartistview ON songview.idSong = songartistview.idSong ORDER BY songview.idAlbum DESC, songview.idSong, songartistview.idRole, songartistview.iOrder 09:28:06.076 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:06.078 T:3919582112 DEBUG: Previous line repeats 1 times. 09:28:06.078 T:3919582112 DEBUG: GetRecentlyAddedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM album WHERE strAlbum != '' ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN albumview ON albumview.idAlbum = recentalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY albumview.idAlbum desc, albumartistview.iOrder 09:28:06.081 T:3919582112 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update 09:28:06.082 T:4114313040 DEBUG: no profile autoexec.py (/storage/.kodi/userdata/autoexec.py) found, skipping 09:28:06.082 T:4114313040 DEBUG: NetworkMessage - Starting network services 09:28:06.089 T:4114313040 DEBUG: CZeroconfAvahi::clientCallback: client is up and running 09:28:06.089 T:4114313040 NOTICE: starting zeroconf publishing 09:28:06.090 T:4114313040 NOTICE: CWebServer[8080]: Started 09:28:06.091 T:3911193504 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webserver type: _http._tcp name:Kodi (LibreELEC) port:8080 09:28:06.091 T:4026524576 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - starting PVR client 09:28:06.091 T:3911193504 DEBUG: CZeroconfAvahi::addService() named: Kodi (LibreELEC) type: _http._tcp port:8080 09:28:06.091 T:4026524576 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'host' 09:28:06.092 T:4026524576 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'htsp_port' 09:28:06.094 T:4026524576 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'http_port' 09:28:06.095 T:4026524576 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'user' 09:28:06.095 T:3902804896 DEBUG: -->Python Interpreter Initialized<-- 09:28:06.095 T:3902804896 DEBUG: CPythonInvoker(1, /usr/share/kodi/addons/service.libreelec.settings/service.py): the source file to load is "/usr/share/kodi/addons/service.libreelec.settings/service.py" 09:28:06.095 T:4026524576 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'pass' 09:28:06.096 T:3902804896 DEBUG: CPythonInvoker(1, /usr/share/kodi/addons/service.libreelec.settings/service.py): setting the Python path to /usr/share/kodi/addons/service.libreelec.settings:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages/gtk-2.0 09:28:06.096 T:3902804896 DEBUG: CPythonInvoker(1, /usr/share/kodi/addons/service.libreelec.settings/service.py): entering source directory /usr/share/kodi/addons/service.libreelec.settings 09:28:06.096 T:3902804896 DEBUG: CPythonInvoker(1, /usr/share/kodi/addons/service.libreelec.settings/service.py): instantiating addon using automatically obtained id of "service.libreelec.settings" dependent on version 2.1.0 of the xbmc.python api 09:28:06.096 T:3911193504 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-http type: _xbmc-jsonrpc-h._tcp name:Kodi (LibreELEC) port:8080 09:28:06.096 T:3911193504 DEBUG: CZeroconfAvahi::addService() named: Kodi (LibreELEC) type: _xbmc-jsonrpc-h._tcp port:8080 09:28:06.102 T:4026524576 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'connect_timeout' 09:28:06.103 T:4026524576 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'response_timeout' 09:28:06.104 T:4114313040 NOTICE: starting upnp client 09:28:06.104 T:4026524576 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'trace_debug' 09:28:06.105 T:4026524576 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'epg_async' 09:28:06.106 T:4026524576 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'pretuner_enabled' 09:28:06.107 T:4026524576 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'autorec_approxtime' 09:28:06.108 T:4026524576 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'autorec_maxdiff' 09:28:06.109 T:4026524576 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'streaming_profile' 09:28:06.110 T:4026524576 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'dvr_priority' 09:28:06.111 T:4026524576 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'dvr_lifetime' 09:28:06.111 T:4026524576 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'dvr_dubdetect' 09:28:06.114 T:4026524576 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connection state change (0 -> 7) 09:28:06.114 T:4026524576 DEBUG: PVR - PVRConnectionStateChange - state for connection '127.0.0.1:9982' on client 'Tvheadend HTSP Client' changed from '0' to '7' 09:28:06.115 T:4114313040 INFO: JSONRPC Server: Successfully initialized 09:28:06.115 T:3919582112 DEBUG: RunQuery took 1 ms for 0 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 10 09:28:06.115 T:4114313040 DEBUG: SECTION:LoadDLL(libshairplay.so.0) 09:28:06.115 T:3911193504 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:Kodi (LibreELEC) port:9090 09:28:06.115 T:3911193504 DEBUG: CZeroconfAvahi::addService() named: Kodi (LibreELEC) type: _xbmc-jsonrpc._tcp port:9090 09:28:06.118 T:4114313040 DEBUG: Loading: libshairplay.so.0 09:28:06.120 T:3802141600 DEBUG: Thread TCPServer start, auto delete: false 09:28:06.120 T:3919582112 DEBUG: RunQuery took 3 ms for 0 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 10 09:28:06.121 T:3810530208 DEBUG: Thread EventServer start, auto delete: false 09:28:06.122 T:3810530208 NOTICE: ES: Starting UDP Event server on port 9777 09:28:06.122 T:3810530208 NOTICE: UDP: Listening on port 9777 (ipv6 : true) 09:28:06.122 T:3911193504 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:Kodi (LibreELEC) port:9777 09:28:06.122 T:3911193504 DEBUG: CZeroconfAvahi::addService() named: Kodi (LibreELEC) type: _xbmc-events._udp port:9777 09:28:06.126 T:3911193504 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.airtunes type: _raop._tcp name:001E06350313@Kodi (LibreELEC) port:36666 09:28:06.126 T:4114313040 INFO: AIRPLAY: Cleaning up photoassetcache 09:28:06.126 T:3911193504 DEBUG: CZeroconfAvahi::addService() named: 001E06350313@Kodi (LibreELEC) type: _raop._tcp port:36666 09:28:06.126 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:06.128 T:3919582112 DEBUG: Previous line repeats 1 times. 09:28:06.128 T:3919582112 DEBUG: RunQuery took 6 ms for 0 items query: select * from musicvideo_view ORDER BY dateAdded desc, idMVideo desc LIMIT 10 09:28:06.129 T:4114313040 INFO: AIRPLAY Server: Successfully initialized 09:28:06.129 T:3835696032 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - new connection requested 09:28:06.129 T:3835696032 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connecting to 127.0.0.1:9982 09:28:06.130 T:3835696032 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connected 09:28:06.130 T:4026524576 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.airplay type: _airplay._tcp name:Kodi (LibreELEC) port:36667 09:28:06.130 T:3776975776 DEBUG: Thread AirPlayServer start, auto delete: false 09:28:06.131 T:4026524576 DEBUG: CZeroconfAvahi::addService() named: Kodi (LibreELEC) type: _airplay._tcp port:36667 09:28:06.132 T:3768587168 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - sending hello 09:28:06.134 T:3919582112 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update 09:28:06.140 T:3919582112 DEBUG: GetArtistsByWhere query: SELECT COUNT(DISTINCT artistview.idArtist) FROM artistview WHERE ((EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artistview.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '')) AND (artistview.strArtist <> 'Verschiedene Interpreten') 09:28:06.318 T:3768587168 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connected to Tvheadend / 4.1.2520 ~ LibreELEC Tvh-addon v8.1.111 (HTSPv26) 09:28:06.318 T:3768587168 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - sending auth 09:28:06.319 T:3768587168 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - rebuilding state 09:28:06.321 T:3768587168 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - async updates requested 09:28:06.322 T:3768587168 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - registered 09:28:06.322 T:3768587168 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connection state change (7 -> 5) 09:28:06.322 T:3768587168 DEBUG: PVR - PVRConnectionStateChange - state for connection '127.0.0.1:9982' on client 'Tvheadend HTSP Client' changed from '7' to '5' 09:28:06.322 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:1, name:ZDFmobil 09:28:06.322 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:2, name:RTL Group 09:28:06.322 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:3, name:UHDTV 09:28:06.322 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:4, name:ARD 09:28:06.322 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:5, name:ProSiebenSat.1 Media 09:28:06.322 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:6, name:DVB-T Netzwerk 09:28:06.322 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:7, name:MEDIA BROADCAST 09:28:06.326 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:8, name:TV channels 09:28:06.327 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:57211496, name:3sat HD 09:28:06.327 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:246693189, name:tagesschau24 HD 09:28:06.327 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:398274308, name:ONE HD 09:28:06.327 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:427450130, name:SAT.1 HD HH/SH 09:28:06.327 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:429801398, name:NDR FS NDS HD* 09:28:06.327 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:473655065, name:NDR FS SH HD 09:28:06.327 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:497512527, name:Das Erste HD 09:28:06.327 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:607445463, name:NDR FS MV HD* 09:28:06.327 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:772427088, name:arte HD 09:28:06.327 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:866725724, name:SWR BW HD* 09:28:06.327 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:968495659, name:zdf_neo HD 09:28:06.327 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1021781906, name:ZDF HD 09:28:06.327 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1225225615, name:MDR S-Anhalt HD 09:28:06.327 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1309132615, name:BR Fernsehen Süd HD* 09:28:06.327 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1323540187, name:NDR FS HH HD* 09:28:06.328 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1569410312, name:NICK HD 09:28:06.328 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1630212372, name:ZDFinfo HD 09:28:06.328 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1830792371, name:KiKA HD 09:28:06.328 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1839581608, name:Infokanal 09:28:06.328 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1841348545, name:WDR HD Köln* 09:28:06.328 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:2137017485, name:PHOENIX HD 09:28:06.328 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:1, name:ZDFmobil 09:28:06.328 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:2, name:RTL Group 09:28:06.328 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:3, name:UHDTV 09:28:06.328 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:4, name:ARD 09:28:06.328 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:5, name:ProSiebenSat.1 Media 09:28:06.328 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:6, name:DVB-T Netzwerk 09:28:06.328 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:7, name:MEDIA BROADCAST 09:28:06.328 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:8, name:TV channels 09:28:06.328 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1082362, state:completed, title:Seidenstraße (7/7), desc:Peter Gysling bereist eine der Nordrouten der Seidenstraße, von Venedig nach Xi'an. Die letzte Etappe führt nach Xi'an zu den Mogao-Grotten in Dunhuang. Die dortigen buddhistischen Skulpturen und Wandmalereien zählen zum UNESCO-Weltkulturerbe. Chang Jiahuang, Sohn eines Konservators, führt Peter Gysling durch die Chang-Grotten. HD-Produktion, error:none 09:28:06.330 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1132600099, state:completed, title:Schleswig-Holstein Magazin, desc:Themen: * Hilfe für 4-jährige Neva: Wie Typisierungen helfen können * Drama vor 70 Jahren: Irrfahrt jüdischer Flüchtlinge auf der "Exodus" * Ausgrenzung und Rassismus: 29-jähriger Kameruner hilft Schülern in Lübeck * Aktivtag des LSV - Philipp Jeß trainiert mit Senioren * Wie war die Nacht der Wissenschaft? * Kulturtipp: Konzert von Jaimi Faulkner im Kieler Kulturforum Produziert in HD, error:none ============================================================= Note: [detailed informations of recordings manually deleted] ============================================================= 6.338 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:2107167143, state:completed, title:heute journal, desc:PHOENIX produziert als Ereignis- und Dokumentationskanal von ARD und ZDF keine eigene Nachrichtensendung, sondern nutzt im Fernseh-Bereich mit der "tagesschau" und dem "heute-journal" die Nachrichten-Formate seiner Muttersender. Produziert in HD, error:none 09:28:06.339 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:609792704, state:completed, title:Hubert und Staller (74), desc:Hubert und Staller stellen einen betrunkenen Autofahrer zur Rede, der mit seinem Wagen in eine Kreuzung gerast war, ohne Rücksicht auf die anderen Verkehrsteilnehmer. Hubert ist wenig begeistert, dass es sich bei dem Betrunkenen um seinen alten Klassenkameraden Günter Ammann handelt. Er hatte gehofft, den ehemaligen Prügelknaben seiner Klasse nie mehr wiedersehen zu müssen. Als Staller jedoch im Kofferraum von Amanns Wagen auch noch die Leiche der ehemaligen Mitschülerin Hanna Bommer findet, bleibt Hubert und Staller nichts anderes übrig, als Ammann festzunehmen. Der aber beteuert seine Unschuld. Produziert in HD, error:none 09:28:06.339 T:3852473248 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - async updates initialised 09:28:06.339 T:3852473248 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - profile name: htsp, comment: HTSP Default Stream Settings added 09:28:06.380 T:4114313040 INFO: Found input device /dev/input/event0 09:28:06.380 T:4114313040 INFO: opened device 'aml_keypad' (file name /dev/input/event0), m_bSkipNonKeyEvents 0 09:28:06.381 T:3902804896 DEBUG: ## LibreELEC Addon ## 0.8.7 09:28:06.385 T:3902804896 WARNING: CSkinInfo: failed to load skin settings 09:28:06.385 T:3902804896 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:06.590 T:4114313040 INFO: Found input device /dev/input/event1 09:28:06.590 T:4114313040 INFO: opened device 'adc_keypad' (file name /dev/input/event1), m_bSkipNonKeyEvents 0 09:28:06.783 T:3902804896 INFO: ## LibreELEC Addon ## system::set_hostname ## LibreELEC 09:28:06.784 T:3902804896 INFO: ## LibreELEC Addon ## system::set_keyboard_layout ## us 09:28:06.784 T:3902804896 INFO: ## LibreELEC Addon ## system::set_keyboard_layout ## loadkmap < `ls -1 /usr/lib/keymaps/*/us.bmap` 09:28:06.825 T:3902804896 INFO: ## LibreELEC Addon ## system::updateThread ## Started 09:28:06.826 T:3902804896 INFO: ## LibreELEC Addon ## system::set_auto_update ## manual 09:28:06.847 T:3735032736 INFO: ## LibreELEC Addon ## _service_::run ## WAITING: 09:28:06.850 T:4114313040 INFO: Found input device /dev/input/event3 09:28:06.850 T:4114313040 INFO: opened device 'cec_input' (file name /dev/input/event3), m_bSkipNonKeyEvents 0 09:28:06.856 T:3743421344 INFO: ## LibreELEC Addon ## xdbus Monitor started. ## 09:28:07.040 T:4114313040 INFO: Found input device /dev/input/event4 09:28:07.040 T:4114313040 INFO: opened device 'HID 4555:1031' (file name /dev/input/event4), m_bSkipNonKeyEvents 0 09:28:07.085 T:3886027680 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established 09:28:07.140 T:3961525152 DEBUG: Previous line repeats 5 times. 09:28:07.140 T:3961525152 DEBUG: OpenConnection - connection to the CEC adapter opened 09:28:07.140 T:3961525152 DEBUG: SetConfigurationFromLibCEC - settings updated by libCEC 09:28:07.140 T:3751809952 DEBUG: Thread CECAdapterUpdate start, auto delete: false 09:28:07.241 T:4114313040 INFO: Found input device /dev/input/event5 09:28:07.241 T:4114313040 INFO: opened device 'HID 4555:1031' (file name /dev/input/event5), m_bSkipNonKeyEvents 0 09:28:07.410 T:4114313040 INFO: Found input device /dev/input/event6 09:28:07.410 T:4114313040 INFO: opened device 'IR-receiver inside an USB DVB receiver' (file name /dev/input/event6), m_bSkipNonKeyEvents 0 09:28:07.410 T:3726644128 DEBUG: Thread CLinuxInputDevicesCheckHotplugged start, auto delete: false 09:28:07.470 T:3751809952 DEBUG: UpdateMenuLanguage - using TV menu language is disabled 09:28:07.737 T:4114313040 DEBUG: ------ Window Init () ------ 09:28:07.741 T:4114313040 DEBUG: ------ Window Init (Pointer.xml) ------ 09:28:07.798 T:4114313040 DEBUG: ------ Window Init (DialogExtendedProgressBar.xml) ------ 09:28:07.798 T:3768587168 DEBUG: CPVRChannelGroups - Load - loading all TV channel groups 09:28:07.798 T:4114313040 DEBUG: LogindUPowerSyscall - Received unknown signal NameAcquired 09:28:07.799 T:4114313040 DEBUG: ------ Window Init (DialogNotification.xml) ------ 09:28:07.802 T:3919582112 INFO: WEATHER: Downloading weather 09:28:07.803 T:3706184608 DEBUG: Thread LanguageInvoker start, auto delete: false 09:28:07.803 T:3706184608 INFO: initializing python engine. 09:28:07.804 T:3706184608 DEBUG: CPythonInvoker(2, /storage/.kodi/addons/weather.openweathermap.extended/default.py): start processing 09:28:07.828 T:3768587168 DEBUG: PVR - Get - group 'All channels' loaded from the database 09:28:07.828 T:3768587168 DEBUG: CPVRChannelGroups - Load - 1 TV groups fetched from the database 09:28:07.830 T:3768587168 DEBUG: PVRChannelGroup - Load - 21 channels loaded from the database for group 'All channels' 09:28:07.831 T:3768587168 DEBUG: CPVRChannelGroups - LoadUserDefinedChannelGroups - 'synchannelgroups' is disabled; skipping groups from clients 09:28:07.831 T:3768587168 DEBUG: CPVRChannelGroups - Load - 1 TV channel groups loaded 09:28:07.831 T:3768587168 DEBUG: CPVRChannelGroups - Load - loading all radio channel groups 09:28:07.831 T:3768587168 DEBUG: CPVRChannelGroups - Load - 1 radio groups fetched from the database 09:28:07.831 T:3768587168 DEBUG: PVRChannelGroup - Load - 0 channels loaded from the database for group 'Alle Kanäle' 09:28:07.832 T:3768587168 DEBUG: CPVRChannelGroups - LoadUserDefinedChannelGroups - 'synchannelgroups' is disabled; skipping groups from clients 09:28:07.832 T:3768587168 DEBUG: CPVRChannelGroups - Load - 1 radio channel groups loaded 09:28:07.833 T:3768587168 DEBUG: CPVRTimers - Update - updating timers 09:28:07.833 T:3768587168 DEBUG: CPVRRecordings - Update - updating recordings 09:28:07.835 T:3696223136 DEBUG: Thread PVRGUIInfo start, auto delete: false 09:28:07.837 T:3768587168 DEBUG: PVRManager - Process - entering main loop 09:28:07.838 T:4026524576 INFO: PVRChannelGroupsContainer - SearchMissingChannelIcons - starting channel icon search 09:28:07.838 T:3768587168 DEBUG: CPVRChannelGroupsContainer - Update - updating channel groups 09:28:07.838 T:3687834528 DEBUG: Thread EPGUpdater start, auto delete: false 09:28:07.838 T:3911193504 NOTICE: Start - EPG thread started 09:28:07.838 T:3768587168 DEBUG: CPVRChannelGroup - Persist - persisting channel group 'All channels' with 21 channels 09:28:07.848 T:3706184608 DEBUG: -->Python Interpreter Initialized<-- 09:28:07.849 T:3706184608 DEBUG: CPythonInvoker(2, /storage/.kodi/addons/weather.openweathermap.extended/default.py): the source file to load is "/storage/.kodi/addons/weather.openweathermap.extended/default.py" 09:28:07.849 T:3706184608 DEBUG: CPythonInvoker(2, /storage/.kodi/addons/weather.openweathermap.extended/default.py): setting the Python path to /storage/.kodi/addons/weather.openweathermap.extended:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages/gtk-2.0 09:28:07.850 T:3706184608 DEBUG: CPythonInvoker(2, /storage/.kodi/addons/weather.openweathermap.extended/default.py): entering source directory /storage/.kodi/addons/weather.openweathermap.extended 09:28:07.851 T:3706184608 DEBUG: CPythonInvoker(2, /storage/.kodi/addons/weather.openweathermap.extended/default.py): instantiating addon using automatically obtained id of "weather.openweathermap.extended" dependent on version 2.25.0 of the xbmc.python api 09:28:07.864 T:3768587168 DEBUG: CPVRChannelGroups - PersistAll - persisting all changes in channel groups 09:28:07.865 T:3768587168 DEBUG: Previous line repeats 1 times. 09:28:07.865 T:3768587168 DEBUG: CPVRChannelGroupsContainer - Update - updating channels 09:28:07.865 T:3768587168 DEBUG: CPVRChannelGroups - PersistAll - persisting all changes in channel groups 09:28:07.865 T:3768587168 DEBUG: Previous line repeats 1 times. 09:28:07.865 T:3768587168 DEBUG: CPVRTimers - Update - updating timers 09:28:07.865 T:3768587168 DEBUG: CPVRRecordings - Update - updating recordings 09:28:07.867 T:3768587168 DEBUG: CPVRChannelGroup - Persist - persisting channel group 'All channels' with 21 channels 09:28:07.906 T:3768587168 DEBUG: CPVRTimers - Update - updating timers 09:28:07.914 T:4114313040 DEBUG: ------ Window Deinit (DialogExtendedProgressBar.xml) ------ 09:28:08.021 T:3706184608 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:28:08.167 T:3706184608 DEBUG: Previous line repeats 40 times. 09:28:08.167 T:3706184608 INFO: CPythonInvoker(2, /storage/.kodi/addons/weather.openweathermap.extended/default.py): script successfully run 09:28:08.211 T:3706184608 INFO: Python script stopped 09:28:08.211 T:3706184608 DEBUG: Thread LanguageInvoker 3706184608 terminating 09:28:08.317 T:3919582112 DEBUG: POParser: loaded 130 weather tokens 09:28:08.818 T:4114313040 DEBUG: ------ Window Deinit (Pointer.xml) ------ 09:28:08.873 T:4114313040 DEBUG: ------ Window Init (DialogExtendedProgressBar.xml) ------ 09:28:08.874 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel '3sat HD' from client '2146250725' 09:28:08.874 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 57211496 start 1507274888 stop 1507274888 09:28:08.883 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 57211496 events 95 09:28:08.887 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'tagesschau24 HD' from client '2146250725' 09:28:08.887 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 246693189 start 1507274888 stop 1507274888 09:28:08.898 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 246693189 events 130 09:28:08.903 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'ONE HD' from client '2146250725' 09:28:08.903 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 398274308 start 1507274888 stop 1507274888 09:28:08.911 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 398274308 events 93 09:28:08.915 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'SAT.1 HD HH/SH' from client '2146250725' 09:28:08.915 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 427450130 start 1507274888 stop 1507274888 09:28:08.919 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 427450130 events 40 09:28:08.920 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'NDR FS NDS HD*' from client '2146250725' 09:28:08.920 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 429801398 start 1507274888 stop 1507274888 09:28:08.929 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 429801398 events 115 09:28:08.934 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'NDR FS SH HD' from client '2146250725' 09:28:08.934 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 473655065 start 1507274888 stop 1507274888 09:28:08.944 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 473655065 events 115 09:28:08.948 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'Das Erste HD' from client '2146250725' 09:28:08.948 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 497512527 start 1507274888 stop 1507274888 09:28:08.958 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 497512527 events 111 09:28:08.962 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'NDR FS MV HD*' from client '2146250725' 09:28:08.962 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 607445463 start 1507274888 stop 1507274888 09:28:08.970 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 607445463 events 115 09:28:08.974 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'arte HD' from client '2146250725' 09:28:08.974 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 772427088 start 1507274888 stop 1507274888 09:28:08.984 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 772427088 events 100 09:28:08.988 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'SWR BW HD*' from client '2146250725' 09:28:08.988 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 866725724 start 1507274888 stop 1507274888 09:28:08.998 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 866725724 events 110 09:28:09.002 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'zdf_neo HD' from client '2146250725' 09:28:09.002 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 968495659 start 1507274888 stop 1507274888 09:28:09.008 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 968495659 events 81 09:28:09.011 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'ZDF HD' from client '2146250725' 09:28:09.011 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1021781906 start 1507274888 stop 1507274888 09:28:09.019 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1021781906 events 107 09:28:09.023 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'MDR S-Anhalt HD' from client '2146250725' 09:28:09.023 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1225225615 start 1507274888 stop 1507274888 09:28:09.034 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1225225615 events 117 09:28:09.038 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'BR Fernsehen Süd HD*' from client '2146250725' 09:28:09.038 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1309132615 start 1507274888 stop 1507274888 09:28:09.048 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1309132615 events 100 09:28:09.051 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'NDR FS HH HD*' from client '2146250725' 09:28:09.051 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1323540187 start 1507274888 stop 1507274888 09:28:09.059 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1323540187 events 115 09:28:09.063 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'NICK HD' from client '2146250725' 09:28:09.063 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1569410312 start 1507274888 stop 1507274888 09:28:09.065 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1569410312 events 8 09:28:09.068 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'ZDFinfo HD' from client '2146250725' 09:28:09.068 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1630212372 start 1507274888 stop 1507274888 09:28:09.076 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1630212372 events 104 09:28:09.080 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'KiKA HD' from client '2146250725' 09:28:09.080 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1830792371 start 1507274888 stop 1507274888 09:28:09.089 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1830792371 events 122 09:28:09.093 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'Infokanal' from client '2146250725' 09:28:09.093 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1839581608 start 1507274888 stop 1507274888 09:28:09.095 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1839581608 events 13 09:28:09.096 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'WDR HD Köln*' from client '2146250725' 09:28:09.096 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1841348545 start 1507274888 stop 1507274888 09:28:09.105 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 1841348545 events 100 09:28:09.108 T:3687834528 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'PHOENIX HD' from client '2146250725' 09:28:09.108 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 2137017485 start 1507274888 stop 1507274888 09:28:09.117 T:3687834528 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - get epg channel 2137017485 events 93 09:28:09.120 T:3768587168 DEBUG: CPVRTimers - Update - updating timers 09:28:09.396 T:4114313040 DEBUG: ------ Window Deinit (DialogExtendedProgressBar.xml) ------ 09:28:12.670 T:3751809952 DEBUG: UpdateAudioSystemStatus - no CEC capable amplifier found 09:28:18.079 T:4114313040 DEBUG: ------ Window Deinit (DialogNotification.xml) ------ 09:28:37.867 T:3911193504 DEBUG: Thread JobWorker 3911193504 terminating (autodelete) 09:28:37.868 T:4026524576 DEBUG: Thread JobWorker 4026524576 terminating (autodelete) 09:28:38.318 T:3919582112 DEBUG: Thread JobWorker 3919582112 terminating (autodelete) 09:31:06.380 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnScreensaverActivated from xbmc 09:31:06.380 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnScreensaverActivated 09:31:06.382 T:4114313040 DEBUG: ------ Window Init () ------ 09:33:09.153 T:3768587168 DEBUG: CPVRTimers - Update - updating timers ========================================================== Note: Bluray I inserted (playback via Disk menu failed) ========================================================== 09:37:29.209 T:4027245472 INFO: Detecting DVD-ROM media filesystem... 09:37:29.222 T:4027245472 ERROR: Detection of DVD-ROM media failed. 09:37:33.932 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:37:33.973 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, screen saver/dpms woken up 09:37:33.973 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnScreensaverDeactivated from xbmc 09:37:33.973 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnScreensaverDeactivated 09:37:34.100 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 168 09:37:34.875 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:37:34.880 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:37:34.880 T:4082103200 INFO: CActiveAESink::OpenSink - initialize sink 09:37:34.880 T:4082103200 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@:CARD=AMLM8AUDIO,DEV=0 09:37:34.880 T:4082103200 INFO: CAESinkALSA::Initialize - Attempting to open device "@:CARD=AMLM8AUDIO,DEV=0" 09:37:34.885 T:4082103200 INFO: CAESinkALSA - Unable to open device "sysdefault:CARD=AMLM8AUDIO" for playback 09:37:34.887 T:4082103200 INFO: CAESinkALSA - Unable to open device "default:CARD=AMLM8AUDIO" for playback 09:37:34.887 T:4082103200 INFO: CAESinkALSA::Initialize - Opened device "front:CARD=AMLM8AUDIO,DEV=0" 09:37:34.888 T:4082103200 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 09:37:34.888 T:4082103200 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 09:37:34.888 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 09:37:34.890 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192 09:37:34.890 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 186 ms 09:37:34.890 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 09:37:34.890 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 09:37:34.890 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: none) 09:37:34.890 T:4082103200 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 09:37:34.890 T:4082103200 DEBUG: Output Device : AML-M8AUDIO 09:37:34.890 T:4082103200 DEBUG: Sample Rate : 44100 09:37:34.890 T:4082103200 DEBUG: Sample Format : AE_FMT_S32NE 09:37:34.890 T:4082103200 DEBUG: Channel Count : 2 09:37:34.890 T:4082103200 DEBUG: Channel Layout: FL,FR 09:37:34.890 T:4082103200 DEBUG: Frames : 2048 09:37:34.890 T:4082103200 DEBUG: Frame Size : 8 09:37:34.944 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:37:34.997 T:4114313040 DEBUG: ------ Window Deinit () ------ 09:37:35.432 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:37:35.446 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:37:35.501 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 68 09:37:35.909 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:37:35.914 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:37:35.977 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:38:09.186 T:3768587168 DEBUG: CPVRTimers - Update - updating timers 09:40:34.129 T:4114313040 DEBUG: ------ Window Init (DialogNotification.xml) ------ 09:40:34.144 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:40:36.165 T:4114297760 DEBUG: Previous line repeats 7 times. 09:40:36.165 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnScreensaverActivated from xbmc 09:40:36.165 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnScreensaverActivated 09:40:36.167 T:4114313040 DEBUG: ------ Window Init () ------ 09:40:39.897 T:4114313040 DEBUG: ------ Window Deinit (DialogNotification.xml) ------ ========================================================== Note: Bluray II inserted (playback via Disk menu failed) ========================================================== 09:41:29.530 T:4027245472 INFO: Detecting DVD-ROM media filesystem... 09:41:29.551 T:4027245472 ERROR: Detection of DVD-ROM media failed. 09:41:36.433 T:3969913760 DEBUG: PushCecKeypress - received key a6 duration 0 09:41:36.455 T:4114313040 DEBUG: OnKey: 166 (0xa6, obc89) pressed, screen saver/dpms woken up 09:41:36.455 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnScreensaverDeactivated from xbmc 09:41:36.455 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnScreensaverDeactivated 09:41:36.790 T:3969913760 DEBUG: PushCecKeypress - received key a6 duration 357 09:41:37.480 T:4114313040 DEBUG: ------ Window Deinit () ------ 09:41:38.019 T:3969913760 DEBUG: PushCecKeypress - received key a6 duration 0 09:41:38.060 T:4114313040 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up 09:41:38.060 T:4082103200 INFO: CActiveAESink::OpenSink - initialize sink 09:41:38.060 T:4082103200 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@:CARD=AMLM8AUDIO,DEV=0 09:41:38.060 T:4082103200 INFO: CAESinkALSA::Initialize - Attempting to open device "@:CARD=AMLM8AUDIO,DEV=0" 09:41:38.066 T:4082103200 INFO: CAESinkALSA - Unable to open device "sysdefault:CARD=AMLM8AUDIO" for playback 09:41:38.068 T:4082103200 INFO: CAESinkALSA - Unable to open device "default:CARD=AMLM8AUDIO" for playback 09:41:38.069 T:4082103200 INFO: CAESinkALSA::Initialize - Opened device "front:CARD=AMLM8AUDIO,DEV=0" 09:41:38.069 T:4082103200 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 09:41:38.069 T:4082103200 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 09:41:38.069 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 09:41:38.080 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192 09:41:38.080 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 186 ms 09:41:38.080 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 09:41:38.080 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 09:41:38.080 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: none) 09:41:38.080 T:4082103200 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 09:41:38.080 T:4082103200 DEBUG: Output Device : AML-M8AUDIO 09:41:38.080 T:4082103200 DEBUG: Sample Rate : 44100 09:41:38.080 T:4082103200 DEBUG: Sample Format : AE_FMT_S32NE 09:41:38.080 T:4082103200 DEBUG: Channel Count : 2 09:41:38.080 T:4082103200 DEBUG: Channel Layout: FL,FR 09:41:38.080 T:4082103200 DEBUG: Frames : 2048 09:41:38.080 T:4082103200 DEBUG: Frame Size : 8 09:41:38.088 T:3969913760 DEBUG: PushCecKeypress - received key a6 duration 69 09:41:47.641 T:3969913760 DEBUG: PushCecKeypress - received key a8 duration 0 09:41:47.673 T:4114313040 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 09:41:47.710 T:3969913760 DEBUG: PushCecKeypress - received key a8 duration 69 09:41:48.666 T:3969913760 DEBUG: PushCecKeypress - received key b duration 0 09:41:48.695 T:4114313040 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 09:41:48.735 T:3969913760 DEBUG: PushCecKeypress - received key b duration 69 09:43:09.219 T:3768587168 DEBUG: CPVRTimers - Update - updating timers 09:43:31.230 T:4114313040 DEBUG: ------ Window Init (DialogNotification.xml) ------ 09:43:31.580 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:43:37.029 T:4114313040 DEBUG: Previous line repeats 7 times. 09:43:37.034 T:4114313040 DEBUG: ------ Window Deinit (DialogNotification.xml) ------ ============================================== Note: DVD inserted (playback via Disk menu ok) ============================================== 09:43:58.482 T:4027245472 INFO: Detecting DVD-ROM media filesystem... 09:43:58.497 T:4027245472 INFO: CD Analysis Report 09:43:58.497 T:4027245472 INFO: __________________________________ 09:43:58.515 T:4027245472 INFO: CD-ROM with ISO 9660 filesystem 09:43:58.515 T:4027245472 INFO: ISO 9660: 1151849 blocks, label 09:43:58.517 T:4027245472 INFO: Tracks overall:1; Audio tracks:0; Data tracks:1 09:44:03.461 T:4027245472 INFO: Using protocol iso9660:// 09:44:03.461 T:4027245472 INFO: Disc label: 09:44:06.422 T:4114313040 NOTICE: UDev: Changed / Added /var/media/ANTONIAS_WELT 09:44:06.422 T:4114313040 DEBUG: ------ Window Init (DialogNotification.xml) ------ 09:44:08.514 T:4114313040 NOTICE: UDev: Changed / Added /var/media/ANTONIAS_WELT 09:44:13.912 T:4114313040 DEBUG: ------ Window Deinit (DialogNotification.xml) ------ 09:44:20.813 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:44:20.835 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:44:20.835 T:4082103200 INFO: CActiveAESink::OpenSink - initialize sink 09:44:20.835 T:4082103200 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@:CARD=AMLM8AUDIO,DEV=0 09:44:20.836 T:4082103200 INFO: CAESinkALSA::Initialize - Attempting to open device "@:CARD=AMLM8AUDIO,DEV=0" 09:44:20.841 T:4082103200 INFO: CAESinkALSA - Unable to open device "sysdefault:CARD=AMLM8AUDIO" for playback 09:44:20.842 T:4082103200 INFO: CAESinkALSA - Unable to open device "default:CARD=AMLM8AUDIO" for playback 09:44:20.843 T:4082103200 INFO: CAESinkALSA::Initialize - Opened device "front:CARD=AMLM8AUDIO,DEV=0" 09:44:20.843 T:4082103200 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 09:44:20.843 T:4082103200 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 09:44:20.843 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 09:44:20.850 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192 09:44:20.850 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 186 ms 09:44:20.850 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 09:44:20.850 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 09:44:20.850 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: none) 09:44:20.850 T:4082103200 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 09:44:20.850 T:4082103200 DEBUG: Output Device : AML-M8AUDIO 09:44:20.850 T:4082103200 DEBUG: Sample Rate : 44100 09:44:20.850 T:4082103200 DEBUG: Sample Format : AE_FMT_S32NE 09:44:20.850 T:4082103200 DEBUG: Channel Count : 2 09:44:20.850 T:4082103200 DEBUG: Channel Layout: FL,FR 09:44:20.850 T:4082103200 DEBUG: Frames : 2048 09:44:20.850 T:4082103200 DEBUG: Frame Size : 8 09:44:20.882 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:44:22.058 T:3969913760 DEBUG: PushCecKeypress - received key a9 duration 0 09:44:22.059 T:4114313040 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 09:44:22.127 T:3969913760 DEBUG: PushCecKeypress - received key a9 duration 69 09:44:23.179 T:3969913760 DEBUG: PushCecKeypress - received key a6 duration 0 09:44:23.190 T:4114313040 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up 09:44:23.248 T:3969913760 DEBUG: PushCecKeypress - received key a6 duration 69 09:44:26.131 T:3969913760 DEBUG: PushCecKeypress - received key a8 duration 0 09:44:26.148 T:4114313040 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 09:44:26.199 T:3969913760 DEBUG: PushCecKeypress - received key a8 duration 69 09:44:27.017 T:3969913760 DEBUG: PushCecKeypress - received key b duration 0 09:44:27.057 T:4114313040 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 09:44:27.074 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:44:27.086 T:3969913760 DEBUG: PushCecKeypress - received key b duration 69 09:44:27.321 T:4114313040 DEBUG: GetDiskUniqueId: Trying to retrieve ID for path /dev/cdrom 09:44:27.321 T:4114313040 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/VideoPlayer/libdvdnav-arm.so) 09:44:27.322 T:4114313040 DEBUG: Loading: /usr/lib/kodi/system/players/VideoPlayer/libdvdnav-arm.so 09:44:27.324 T:4114313040 INFO: msg: libdvdnav: Using dvdnav version 5.0.4 09:44:27.714 T:4114313040 DEBUG: msg: libdvdnav: DVD Title: 09:44:27.714 T:4114313040 DEBUG: msg: A 09:44:27.714 T:4114313040 DEBUG: msg: N 09:44:27.714 T:4114313040 DEBUG: msg: T 09:44:27.715 T:4114313040 DEBUG: msg: O 09:44:27.715 T:4114313040 DEBUG: msg: N 09:44:27.715 T:4114313040 DEBUG: msg: I 09:44:27.715 T:4114313040 DEBUG: msg: A 09:44:27.715 T:4114313040 DEBUG: msg: S 09:44:27.715 T:4114313040 DEBUG: msg: _ 09:44:27.715 T:4114313040 DEBUG: msg: W 09:44:27.715 T:4114313040 DEBUG: msg: E 09:44:27.715 T:4114313040 DEBUG: msg: L 09:44:27.715 T:4114313040 DEBUG: msg: T 09:44:27.715 T:4114313040 DEBUG: msg: libdvdnav: DVD Serial Number: 09:44:27.715 T:4114313040 DEBUG: msg: 4 09:44:27.715 T:4114313040 DEBUG: msg: 0 09:44:27.715 T:4114313040 DEBUG: msg: A 09:44:27.715 T:4114313040 DEBUG: msg: D 09:44:27.715 T:4114313040 DEBUG: Previous line repeats 1 times. 09:44:27.715 T:4114313040 DEBUG: msg: 3 09:44:27.715 T:4114313040 DEBUG: msg: B 09:44:27.715 T:4114313040 DEBUG: msg: D 09:44:27.715 T:4114313040 DEBUG: msg: _ 09:44:27.715 T:4114313040 DEBUG: Previous line repeats 2 times. 09:44:27.715 T:4114313040 DEBUG: msg: M 09:44:27.715 T:4114313040 DEBUG: msg: V 09:44:27.715 T:4114313040 DEBUG: msg: B 09:44:27.715 T:4114313040 DEBUG: msg: _ 09:44:27.715 T:4114313040 DEBUG: Previous line repeats 1 times. 09:44:27.715 T:4114313040 DEBUG: msg: libdvdnav: DVD Title (Alternative): 09:44:27.715 T:4114313040 DEBUG: msg: 09:44:27.715 T:4114313040 INFO: msg: libdvdnav: vm: DVD Title: ANTONIAS_WELT 09:44:27.715 T:4114313040 INFO: msg: libdvdnav: DVD disk reports itself with Region mask 0x00fd0000. Regions: 09:44:27.715 T:4114313040 INFO: msg: 2 09:44:27.715 T:4114313040 DEBUG: msg: 09:44:27.715 T:4114313040 DEBUG: Previous line repeats 1 times. 09:44:27.715 T:4114313040 DEBUG: msg: libdvdread: Attempting to retrieve all CSS keys 09:44:27.715 T:4114313040 DEBUG: msg: libdvdread: This can take a _long_ time, please be patient 09:44:27.715 T:4114313040 INFO: msg: libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x0000012c 09:44:27.720 T:4114313040 INFO: msg: libdvdread: Elapsed time 0 09:44:27.720 T:4114313040 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x00000194 09:44:27.720 T:4114313040 INFO: msg: libdvdread: Elapsed time 0 09:44:27.720 T:4114313040 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x00009fc8 09:44:27.721 T:4114313040 INFO: msg: libdvdread: Elapsed time 0 09:44:27.721 T:4114313040 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x0000a13a 09:44:27.721 T:4114313040 INFO: msg: libdvdread: Elapsed time 0 09:44:27.721 T:4114313040 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x0000a187 09:44:27.721 T:4114313040 INFO: msg: libdvdread: Elapsed time 0 09:44:27.722 T:4114313040 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_03_0.VOB at 0x00014848 09:44:27.722 T:4114313040 INFO: msg: libdvdread: Elapsed time 0 09:44:27.722 T:4114313040 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x00014895 09:44:27.722 T:4114313040 INFO: msg: libdvdread: Elapsed time 0 09:44:27.722 T:4114313040 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_04_0.VOB at 0x0001920e 09:44:27.723 T:4114313040 INFO: msg: libdvdread: Elapsed time 0 09:44:27.723 T:4114313040 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x0001925b 09:44:27.723 T:4114313040 INFO: msg: libdvdread: Elapsed time 0 09:44:27.723 T:4114313040 INFO: msg: libdvdread: Found 4 VTS's 09:44:27.723 T:4114313040 INFO: msg: libdvdread: Elapsed time 0 09:44:27.724 T:4114313040 DEBUG: Open - Setting region mask 02 09:44:27.724 T:4114313040 DEBUG: GetDiskUniqueId: Got ID removable://ANTONIAS_WELT_40ADD3BD___MVB for DVD disk 09:44:27.729 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:44:27.733 T:4114297760 DEBUG: Previous line repeats 1 times. 09:44:27.733 T:4114297760 DEBUG: GetMovieId (removable://ANTONIAS_WELT_40ADD3BD___MVB/), query = select idMovie from movie where idFile=21 09:44:27.733 T:4114297760 DEBUG: GetEpisodeId (removable://ANTONIAS_WELT_40ADD3BD___MVB/), query = select idEpisode from episode where idFile=21 09:44:27.733 T:4114297760 DEBUG: GetMusicVideoId (removable://ANTONIAS_WELT_40ADD3BD___MVB/), query = select idMVideo from musicvideo where idFile=21 09:44:27.736 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 09:44:27.736 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 09:44:27.738 T:4114313040 DEBUG: Loading settings for /var/media/ANTONIAS_WELT/VIDEO_TS/VIDEO_TS.IFO 09:44:27.745 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers(/var/media/ANTONIAS_WELT/VIDEO_TS/VIDEO_TS.IFO) 09:44:27.746 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 09:44:27.746 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 09:44:27.746 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 09:44:27.746 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 09:44:27.746 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 09:44:27.746 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 09:44:27.746 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 09:44:27.746 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 09:44:27.746 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 09:44:27.746 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 09:44:27.746 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 09:44:27.746 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 09:44:27.746 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 09:44:27.746 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 09:44:27.746 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 09:44:27.746 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 09:44:27.746 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 09:44:27.749 T:4114313040 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData 09:44:27.750 T:4114313040 NOTICE: VideoPlayer: Opening: /var/media/ANTONIAS_WELT/VIDEO_TS/VIDEO_TS.IFO 09:44:27.750 T:4114313040 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 09:44:27.750 T:4114313040 DEBUG: LinuxRendererGL: Cleaning up GL resources 09:44:27.750 T:3919582112 DEBUG: Thread VideoPlayer start, auto delete: false 09:44:27.750 T:3919582112 NOTICE: Creating InputStream 09:44:27.750 T:3919582112 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:44:27.751 T:3919582112 DEBUG: Previous line repeats 1 times. 09:44:27.751 T:3919582112 INFO: msg: libdvdnav: Using dvdnav version 5.0.4 09:44:27.751 T:3919582112 INFO: dll_fopen - something opened the mount file, let's hope it knows what it's doing 09:44:27.752 T:3919582112 INFO: msg: libdvdread: Attempting to use device /dev/sr0 mounted on /var/media/ANTONIAS_WELT for CSS authentication 09:44:27.805 T:3919582112 DEBUG: msg: libdvdnav: Can't read name block. Probably not a DVD-ROM device. 09:44:27.805 T:3919582112 DEBUG: msg: libdvdnav: vm: dvd_read_name failed 09:44:27.805 T:3919582112 INFO: msg: libdvdnav: vm: DVD Title: ANTONIAS_WELT 09:44:27.805 T:3919582112 INFO: msg: libdvdnav: DVD disk reports itself with Region mask 0x00fd0000. Regions: 09:44:27.805 T:3919582112 INFO: msg: 2 09:44:27.805 T:3919582112 DEBUG: msg: 09:44:27.805 T:3919582112 DEBUG: Previous line repeats 1 times. 09:44:27.805 T:3919582112 DEBUG: msg: libdvdread: Attempting to retrieve all CSS keys 09:44:27.805 T:3919582112 DEBUG: msg: libdvdread: This can take a _long_ time, please be patient 09:44:27.805 T:3919582112 INFO: msg: libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x0000012c 09:44:27.805 T:3919582112 INFO: msg: libdvdread: Elapsed time 0 09:44:27.805 T:3919582112 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x00000194 09:44:27.805 T:3919582112 INFO: msg: libdvdread: Elapsed time 0 09:44:27.805 T:3919582112 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x00009fc8 09:44:27.805 T:3919582112 INFO: msg: libdvdread: Elapsed time 0 09:44:27.805 T:3919582112 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x0000a13a 09:44:27.805 T:3919582112 INFO: msg: libdvdread: Elapsed time 0 09:44:27.806 T:3919582112 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x0000a187 09:44:27.806 T:3919582112 INFO: msg: libdvdread: Elapsed time 0 09:44:27.806 T:3919582112 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_03_0.VOB at 0x00014848 09:44:27.806 T:3919582112 INFO: msg: libdvdread: Elapsed time 0 09:44:27.806 T:3919582112 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x00014895 09:44:27.806 T:3919582112 INFO: msg: libdvdread: Elapsed time 0 09:44:27.806 T:3919582112 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_04_0.VOB at 0x0001920e 09:44:27.806 T:3919582112 INFO: msg: libdvdread: Elapsed time 0 09:44:27.806 T:3919582112 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x0001925b 09:44:27.806 T:3919582112 INFO: msg: libdvdread: Elapsed time 0 09:44:27.806 T:3919582112 INFO: msg: libdvdread: Found 4 VTS's 09:44:27.806 T:3919582112 INFO: msg: libdvdread: Elapsed time 0 09:44:27.806 T:3919582112 DEBUG: Open - Setting region mask 02 09:44:27.806 T:3919582112 NOTICE: VideoPlayer: playing a file with menu's 09:44:27.807 T:3919582112 WARNING: CDVDInputStreamNavigator::SetNavigatorState - Failed to set state (Virtual DVD machine not started.), retrying after read 09:44:27.813 T:3919582112 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 1 09:44:27.814 T:3919582112 DEBUG: - DVDNAV_VTS_CHANGE (HOLDING) 09:44:28.019 T:3919582112 NOTICE: Creating Demuxer 09:44:28.020 T:3919582112 DEBUG: Open - avformat_find_stream_info starting 09:44:28.020 T:3919582112 WARNING: could not find codec parameters for /var/media/ANTONIAS_WELT/VIDEO_TS/VIDEO_TS.IFO 09:44:28.020 T:3919582112 DEBUG: Open - av_find_stream_info finished 09:44:28.020 T:3919582112 INFO: ffmpeg[E9A013A0]: Input #0, mpeg, /var/media/ANTONIAS_WELT/VIDEO_TS/VIDEO_TS.IF': 09:44:28.020 T:3919582112 INFO: ffmpeg[E9A013A0]: Duration: N/A, bitrate: N/A 09:44:28.020 T:3919582112 INFO: GetVideoAspectRatio - Aspect wanted: 0, Scale permissions: 3 09:44:28.020 T:3919582112 WARNING: CDVDInputStreamNavigator::GetVideoResolution - Failed to get resolution (Virtual DVD machine not started.) 09:44:28.020 T:3919582112 NOTICE: Opening stream: 1 source: 512 09:44:28.020 T:3919582112 INFO: GetVideoAspectRatio - Aspect wanted: 0, Scale permissions: 3 09:44:28.021 T:3919582112 NOTICE: Creating video codec with codec id: 0 09:44:28.021 T:3919582112 DEBUG: FactoryCodec - Video: amcodec - Opening 09:44:28.021 T:3919582112 DEBUG: DVDVideoCodecAmlogic: Unknown hints.codec(0 09:44:28.021 T:3919582112 DEBUG: FactoryCodec - Video: amcodec - Failed 09:44:28.021 T:3919582112 DEBUG: FactoryCodec - Video: - Opening 09:44:28.021 T:3919582112 DEBUG: CDVDVideoCodecFFmpeg::Open() Unable to find codec 0 09:44:28.021 T:3919582112 DEBUG: FactoryCodec - Video: - Failed 09:44:28.021 T:3919582112 ERROR: Unsupported video codec 09:44:28.021 T:3919582112 NOTICE: Opening stream: 0 source: 512 09:44:28.021 T:3919582112 NOTICE: Finding audio codec for: 0 09:44:28.021 T:3919582112 DEBUG: FactoryCodec - Audio: passthrough - Opening 09:44:28.021 T:3919582112 DEBUG: FactoryCodec - Audio: passthrough - Failed 09:44:28.021 T:3919582112 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 09:44:28.021 T:3919582112 DEBUG: CDVDAudioCodecFFmpeg::Open() Unable to find codec 0 09:44:28.021 T:3919582112 DEBUG: FactoryCodec - Audio: FFmpeg - Failed 09:44:28.021 T:3919582112 ERROR: Unsupported audio codec 09:44:28.021 T:3919582112 NOTICE: Opening stream: 1 source: 512 09:44:28.021 T:3919582112 NOTICE: Finding audio codec for: 0 09:44:28.021 T:3919582112 DEBUG: FactoryCodec - Audio: passthrough - Opening 09:44:28.021 T:3919582112 DEBUG: FactoryCodec - Audio: passthrough - Failed 09:44:28.021 T:3919582112 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 09:44:28.021 T:3919582112 DEBUG: CDVDAudioCodecFFmpeg::Open() Unable to find codec 0 09:44:28.021 T:3919582112 DEBUG: FactoryCodec - Audio: FFmpeg - Failed 09:44:28.021 T:3919582112 ERROR: Unsupported audio codec 09:44:28.021 T:3919582112 NOTICE: Opening stream: 0 source: 512 09:44:28.021 T:3919582112 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opening 09:44:28.021 T:3919582112 DEBUG: Open - Unable to find codec 0 09:44:28.022 T:3919582112 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Failed 09:44:28.022 T:3919582112 ERROR: OpenStream - Unable to init overlay codec 09:44:28.023 T:3919582112 DEBUG: OnPlayBackStarted: play state was 1, starting 1 09:44:28.023 T:3919582112 DEBUG: CVideoPlayer::SetCaching - caching state 2 09:44:28.023 T:3919582112 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 09:44:28.023 T:3919582112 DEBUG: CVideoPlayer::SetCaching - caching state 0 09:44:28.023 T:3919582112 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 09:44:28.023 T:3919582112 NOTICE: VideoPlayer: next stream, wait for old streams to be finished 09:44:28.023 T:3919582112 NOTICE: Creating Demuxer 09:44:28.023 T:4114313040 DEBUG: PlayFile: OpenFile succeed, play state 2 09:44:28.023 T:4114313040 DEBUG: OnPlayBackStarted: play state was 2, starting 0 09:44:28.023 T:3919582112 DEBUG: DVDNAV_VTS_CHANGE 09:44:28.023 T:3919582112 INFO: GetVideoAspectRatio - Aspect wanted: 0, Scale permissions: 3 09:44:28.024 T:3919582112 INFO: Previous line repeats 1 times. 09:44:28.024 T:3919582112 WARNING: CDVDInputStreamNavigator::GetVideoResolution - Failed to get resolution (Virtual DVD machine not started.) 09:44:28.024 T:3919582112 DEBUG: DVDNAV_HOP_CHANNEL 09:44:28.024 T:3919582112 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 09:44:28.024 T:3919582112 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 09:44:28.024 T:3919582112 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 09:44:28.024 T:3919582112 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 09:44:28.024 T:3919582112 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 09:44:28.028 T:4026524576 DEBUG: Thread JobWorker start, auto delete: true 09:44:28.029 T:4026524576 DEBUG: CGUIInfoManager::SetCurrentMovie(/var/media/ANTONIAS_WELT/VIDEO_TS/VIDEO_TS.IFO) 09:44:28.042 T:4026524576 DEBUG: GetMovieId (removable://ANTONIAS_WELT_40ADD3BD___MVB/), query = select idMovie from movie where idFile=21 09:44:28.043 T:4026524576 DEBUG: Previous line repeats 1 times. 09:44:28.043 T:4026524576 DEBUG: GetEpisodeId (removable://ANTONIAS_WELT_40ADD3BD___MVB/), query = select idEpisode from episode where idFile=21 09:44:28.043 T:4026524576 DEBUG: Previous line repeats 1 times. 09:44:28.043 T:4026524576 DEBUG: GetMusicVideoId (removable://ANTONIAS_WELT_40ADD3BD___MVB/), query = select idMVideo from musicvideo where idFile=21 09:44:28.046 T:4114297760 DEBUG: Previous line repeats 1 times. 09:44:28.047 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 09:44:28.047 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 09:44:28.074 T:3911193504 DEBUG: Thread JobWorker start, auto delete: true 09:44:29.024 T:3919582112 DEBUG: CDVDMsgGeneralSynchronize - global timeout 09:44:29.025 T:3919582112 DEBUG: CVideoPlayer::SetCaching - caching state 2 09:44:29.025 T:3919582112 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 09:44:29.026 T:3919582112 DEBUG: DVDNAV_VTS_CHANGE 09:44:29.026 T:3919582112 INFO: GetVideoAspectRatio - Aspect wanted: 0, Scale permissions: 3 09:44:29.026 T:3919582112 INFO: Previous line repeats 1 times. 09:44:29.026 T:3919582112 WARNING: CDVDInputStreamNavigator::GetVideoResolution - Failed to get resolution (Virtual DVD machine not started.) 09:44:29.026 T:3919582112 DEBUG: Open - avformat_find_stream_info starting 09:44:29.027 T:3919582112 DEBUG: ProcessBlock - Cell change: Title 4, Chapter 3 09:44:29.027 T:3919582112 DEBUG: ProcessBlock - At position 15% inside the feature 09:44:29.027 T:3919582112 DEBUG: DVDNAV_CELL_CHANGE 09:44:29.297 T:3919582112 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:0 TO:886240000 DIFF:886240000 09:44:29.558 T:3919582112 DEBUG: Open - av_find_stream_info finished 09:44:29.558 T:3919582112 INFO: ffmpeg[E9A013A0]: Input #0, mpeg, /var/media/ANTONIAS_WELT/VIDEO_TS/VIDEO_TS.IF': 09:44:29.558 T:3919582112 INFO: ffmpeg[E9A013A0]: Duration: N/A, start: 886.036878, bitrate: N/A 09:44:29.559 T:3919582112 INFO: ffmpeg[E9A013A0]: Stream #0:0[0x1e0]: Video: mpeg2video (Main), yuv420p(tv), 720x576 [SAR 16:15 DAR 4:3], 25 fps, 25 tbr, 90k tbn, 50 tbc 09:44:29.559 T:3919582112 INFO: ffmpeg[E9A013A0]: Stream #0:1[0x80]: Audio: ac3, 48000 Hz, 5.1(side), fltp, 448 kb/s 09:44:29.559 T:3919582112 INFO: ffmpeg[E9A013A0]: Stream #0:2[0x81]: Audio: ac3, 48000 Hz, stereo, fltp, 192 kb/s 09:44:29.559 T:3919582112 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 09:44:29.559 T:3919582112 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 09:44:29.559 T:3919582112 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 2 09:44:29.560 T:3919582112 INFO: GetVideoAspectRatio - Aspect wanted: 0, Scale permissions: 3 09:44:29.560 T:3919582112 WARNING: CDVDInputStreamNavigator::GetVideoResolution - Failed to get resolution (Virtual DVD machine not started.) 09:44:29.561 T:3919582112 NOTICE: Opening stream: 1 source: 256 09:44:29.561 T:3919582112 NOTICE: Finding audio codec for: 86019 09:44:29.561 T:3919582112 DEBUG: FactoryCodec - Audio: passthrough - Opening 09:44:29.561 T:3919582112 DEBUG: FactoryCodec - Audio: passthrough - Failed 09:44:29.561 T:3919582112 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 09:44:29.562 T:3919582112 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 09:44:29.562 T:3919582112 NOTICE: Creating audio thread 09:44:29.562 T:3706184608 DEBUG: Thread VideoPlayerAudio start, auto delete: false 09:44:29.562 T:3706184608 NOTICE: running thread: CVideoPlayerAudio::Process() 09:44:29.562 T:3706184608 DEBUG: CDVDAudio::Pause - pausing audio stream 09:44:29.562 T:3706184608 ERROR: ffmpeg[DCE7E3A0]: [ac3] frame sync error 09:44:29.563 T:3706184608 ERROR: CVideoPlayerAudio::DecodeFrame - Decode Error. Skipping audio packet (-1094995529) 09:44:29.563 T:3919582112 NOTICE: Opening stream: 0 source: 256 09:44:29.563 T:3919582112 INFO: GetVideoAspectRatio - Aspect wanted: 0, Scale permissions: 3 09:44:29.563 T:3919582112 NOTICE: Creating video codec with codec id: 2 09:44:29.563 T:3919582112 DEBUG: FactoryCodec - Video: amcodec - Opening 09:44:29.563 T:3919582112 DEBUG: SECTION:LoadDLL(libamplayer.so) 09:44:29.565 T:3919582112 DEBUG: Loading: libamplayer.so 09:44:29.565 T:3706184608 NOTICE: Creating audio stream (codec id: 86019, channels: 6, sample rate: 48000, no pass-through) 09:44:29.566 T:3706184608 DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback 09:44:29.570 T:3919582112 ERROR: Unable to load libamplayer.so, reason: libamplayer.so: cannot open shared object file: No such file or directory 09:44:29.570 T:3919582112 DEBUG: Dll libamplayer.so was not found in path 09:44:29.570 T:3919582112 WARNING: CAMLCodec::CAMLCodec libamplayer.so not found, trying libamcodec.so instead 09:44:29.570 T:3919582112 DEBUG: SECTION:LoadDLL(libamcodec.so) 09:44:29.571 T:3919582112 DEBUG: Loading: libamcodec.so 09:44:29.578 T:3919582112 INFO: DVDVideoCodecAmlogic: Opened Amlogic Codec 09:44:29.578 T:3919582112 DEBUG: FactoryCodec - Video: am-mpeg2 - Opened 09:44:29.578 T:3919582112 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 2 09:44:29.578 T:3919582112 NOTICE: Creating video thread 09:44:29.578 T:3663721376 DEBUG: Thread VideoPlayerVideo start, auto delete: false 09:44:29.578 T:3663721376 NOTICE: running thread: video_thread 09:44:29.578 T:3663721376 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 09:44:29.579 T:3663721376 DEBUG: codecid_to_vformat, id(2) -> vformat(0) 09:44:29.579 T:3663721376 DEBUG: codec_tag_to_vdec_type, codec_tag(2) -> vdec_type(0) 09:44:29.579 T:3663721376 DEBUG: CAMLCodec::OpenDecoder hints.width(720), hints.height(576), hints.codec(2), hints.codec_tag(0) 09:44:29.579 T:3663721376 DEBUG: CAMLCodec::OpenDecoder hints.fpsrate(25000), hints.fpsscale(1000), video_rate(3840) 09:44:29.579 T:3663721376 DEBUG: CAMLCodec::OpenDecoder hints.aspect(1.333333), video_ratio.num(1), video_ratio.den(1) 09:44:29.579 T:3663721376 DEBUG: CAMLCodec::OpenDecoder hints.orientation(0), hints.forced_aspect(1), hints.extrasize(88) 09:44:29.582 T:4082103200 INFO: CActiveAESink::OpenSink - initialize sink 09:44:29.592 T:3663721376 NOTICE: CAMLCodec::OpenDecoder - using V4L2 pts format: 64Bit 09:44:29.768 T:4082103200 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@:CARD=AMLM8AUDIO,DEV=0 09:44:29.768 T:4082103200 INFO: CAESinkALSA::Initialize - Attempting to open device "@:CARD=AMLM8AUDIO,DEV=0" 09:44:29.774 T:4082103200 INFO: CAESinkALSA - Unable to open device "sysdefault:CARD=AMLM8AUDIO" for playback 09:44:29.776 T:4082103200 INFO: CAESinkALSA - Unable to open device "default:CARD=AMLM8AUDIO" for playback 09:44:29.776 T:4082103200 INFO: CAESinkALSA::Initialize - Opened device "front:CARD=AMLM8AUDIO,DEV=0" 09:44:29.777 T:4082103200 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 09:44:29.777 T:4082103200 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 09:44:29.777 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600 09:44:29.777 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 128, bufferSize 9600 09:44:29.777 T:4082103200 DEBUG: Audio Driver reports too low periodSize 128 - will use 2 fragments 09:44:29.777 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 09:44:29.778 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 09:44:29.778 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 09:44:29.778 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: none) 09:44:29.778 T:4082103200 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 09:44:29.778 T:4082103200 DEBUG: Output Device : AML-M8AUDIO 09:44:29.778 T:4082103200 DEBUG: Sample Rate : 48000 09:44:29.778 T:4082103200 DEBUG: Sample Format : AE_FMT_S32NE 09:44:29.778 T:4082103200 DEBUG: Channel Count : 2 09:44:29.778 T:4082103200 DEBUG: Channel Layout: FL,FR 09:44:29.778 T:4082103200 DEBUG: Frames : 256 09:44:29.778 T:4082103200 DEBUG: Frame Size : 8 09:44:29.783 T:4092289952 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 09:44:29.933 T:3919582112 DEBUG: Previous line repeats 1 times. 09:44:29.933 T:3919582112 DEBUG: CVideoPlayer::HandleMessages - player started 1 09:44:29.936 T:3663721376 DEBUG: CRenderManager::Configure - change configuration. 720x576. display: 768x576. framerate: 25.00. format: AMLCODEC 09:44:29.948 T:4114313040 DEBUG: DeleteRenderer - deleting renderer 09:44:29.948 T:4114313040 DEBUG: LinuxRendererGL: Cleaning up GL resources 09:44:29.948 T:4114313040 DEBUG: CRenderManager::Configure - 4 09:44:29.963 T:4114313040 DEBUG: CAMLCodec::SetVideo3dMode:mode3d(0x0) 09:44:29.963 T:4114313040 DEBUG: CAMLCodec::SetVideoRect:display(0,0,1920,1080) 09:44:29.963 T:4114313040 DEBUG: CAMLCodec::SetVideoRect:gui(0,0,1920,1080) 09:44:29.963 T:4114313040 DEBUG: CAMLCodec::SetVideoRect:m_dst_rect(257,24,1412,1027) 09:44:29.963 T:4114313040 DEBUG: CAMLCodec::SetVideoRect:dst_rect(257,24,1412,1027) 09:44:29.963 T:4114313040 DEBUG: CAMLCodec::SetVideoRect:m_stereo_mode(0) 09:44:29.963 T:4114313040 DEBUG: CAMLCodec::SetVideoRect:m_stereo_view(0) 09:44:29.963 T:4114313040 DEBUG: Activating window ID: 12005 09:44:29.963 T:4114313040 DEBUG: ------ Window Deinit (Home.xml) ------ 09:44:29.964 T:4114313040 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 09:44:29.964 T:4114313040 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY 09:44:29.968 T:4114313040 NOTICE: Display resolution ADJUST : 1920x1080 @ 50.00 - Full Screen (22) (weight: 0.000) 09:44:29.968 T:4114313040 NOTICE: VideoPlayer: OnLostDisplay received 09:44:29.968 T:3706184608 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 1 09:44:30.243 T:4114313040 DEBUG: CreateWindow: Created surface of size 1920x1080 09:44:30.243 T:4114313040 NOTICE: VideoPlayer: OnResetDisplay received 09:44:30.243 T:3706184608 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 0 09:44:30.263 T:4114313040 INFO: GLES: Maximum texture width: 4096 09:44:30.264 T:3919582112 DEBUG: CVideoPlayer::HandleMessages - player started 2 09:44:30.264 T:3919582112 DEBUG: CVideoPlayer::SetCaching - caching state 3 09:44:30.264 T:3919582112 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 09:44:30.264 T:3919582112 DEBUG: CVideoPlayer::SetCaching - caching state 0 09:44:30.264 T:3919582112 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 09:44:30.264 T:3919582112 DEBUG: VideoPlayer::Sync - Audio - pts: 886132877.000000, cache: 323963.422746, totalcache: 600000.023842 09:44:30.265 T:3919582112 DEBUG: VideoPlayer::Sync - Video - pts: 886320000.000000, cache: 50000.000000, totalcache: 100000.000000 09:44:30.265 T:3706184608 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(885808913.577254) 09:44:30.265 T:3706184608 DEBUG: CDVDAudio::Resume - resume audio stream 09:44:30.267 T:4092289952 DEBUG: ActiveAE - start sync of audio stream 09:44:30.369 T:4092289952 DEBUG: ActiveAE::SyncStream - average error of 22.618382, start adjusting 09:44:30.369 T:4092289952 DEBUG: ActiveAE::SyncStream - average error 17.618382 below threshold of 30.000000 09:44:30.454 T:3663721376 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 09:44:30.745 T:4114313040 DEBUG: CAMLCodec::SetVideo3dMode:mode3d(0x0) 09:44:30.745 T:4114313040 DEBUG: CAMLCodec::SetVideoRect:display(0,0,1920,1080) 09:44:30.745 T:4114313040 DEBUG: CAMLCodec::SetVideoRect:gui(0,0,1920,1080) 09:44:30.745 T:4114313040 DEBUG: CAMLCodec::SetVideoRect:m_dst_rect(240,0,1440,1080) 09:44:30.745 T:4114313040 DEBUG: CAMLCodec::SetVideoRect:dst_rect(240,0,1440,1080) 09:44:30.745 T:4114313040 DEBUG: CAMLCodec::SetVideoRect:m_stereo_mode(0) 09:44:30.745 T:4114313040 DEBUG: CAMLCodec::SetVideoRect:m_stereo_view(0) 09:44:30.865 T:3663721376 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 1 09:44:30.866 T:3663721376 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0 09:44:30.866 T:3663721376 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(885808913.577254) 09:44:33.644 T:3663721376 DEBUG: CPullupCorrection: detected pattern of length 1: 20000.00, frameduration: 20000.000000 09:44:34.624 T:3663721376 DEBUG: CalcFrameRate framerate was:25.000000 calculated:50.000000 09:44:34.644 T:3663721376 DEBUG: CRenderManager::Configure - change configuration. 720x576. display: 768x576. framerate: 50.00. format: AMLCODEC 09:44:34.724 T:4114313040 DEBUG: CRenderManager::Configure - 4 09:44:34.744 T:4114313040 NOTICE: Display resolution ADJUST : 1920x1080 @ 50.00 - Full Screen (22) (weight: 0.000) 09:44:36.428 T:3706184608 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:21444.713718, adjusted:20000.000000 09:44:36.891 T:3969913760 DEBUG: PushCecKeypress - received key e0 duration 1000 09:44:36.904 T:4114313040 DEBUG: OnKey: guide (0xe0) pressed, action is Stop 09:44:36.904 T:4114313040 NOTICE: CVideoPlayer::CloseFile() 09:44:36.904 T:4114313040 NOTICE: VideoPlayer: waiting for threads to exit 09:44:36.906 T:3919582112 NOTICE: CVideoPlayer::OnExit() 09:44:36.906 T:3919582112 NOTICE: Closing stream player 1 09:44:36.906 T:3919582112 NOTICE: Waiting for audio thread to exit 09:44:36.908 T:3706184608 NOTICE: thread end: CVideoPlayerAudio::OnExit() 09:44:36.908 T:3919582112 NOTICE: Closing audio device 09:44:36.908 T:3706184608 DEBUG: Thread VideoPlayerAudio 3706184608 terminating 09:44:36.913 T:3919582112 DEBUG: CDVDAudio::Flush - flush audio stream 09:44:36.913 T:4092289952 DEBUG: CActiveAE::DiscardStream - audio stream deleted 09:44:36.913 T:4092289952 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 09:44:36.914 T:4082103200 DEBUG: Previous line repeats 2 times. 09:44:36.914 T:4082103200 INFO: CActiveAESink::OpenSink - initialize sink 09:44:36.914 T:3919582112 NOTICE: Deleting audio codec 09:44:36.914 T:3919582112 NOTICE: Closing stream player 2 09:44:36.914 T:3919582112 NOTICE: waiting for video thread to exit 09:44:36.953 T:3663721376 NOTICE: thread end: video_thread 09:44:36.953 T:3663721376 DEBUG: Thread VideoPlayerVideo 3663721376 terminating 09:44:36.954 T:3919582112 NOTICE: deleting video codec 09:44:36.954 T:3919582112 DEBUG: CAMLCodec::CloseDecoder 09:44:36.956 T:3919582112 DEBUG: OnPlayBackStopped: play state was 2, starting 0 09:44:36.962 T:3919582112 DEBUG: Thread VideoPlayer 3919582112 terminating 09:44:36.963 T:4114313040 NOTICE: VideoPlayer: finished waiting 09:44:36.963 T:4114313040 DEBUG: DeleteRenderer - deleting renderer 09:44:36.963 T:4114313040 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 09:44:36.963 T:4114313040 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 09:44:36.964 T:4114297760 DEBUG: GetMovieId (removable://ANTONIAS_WELT_40ADD3BD___MVB/), query = select idMovie from movie where idFile=21 09:44:36.965 T:4114297760 DEBUG: GetEpisodeId (removable://ANTONIAS_WELT_40ADD3BD___MVB/), query = select idEpisode from episode where idFile=21 09:44:36.966 T:4114297760 DEBUG: GetMusicVideoId (removable://ANTONIAS_WELT_40ADD3BD___MVB/), query = select idMVideo from musicvideo where idFile=21 09:44:36.968 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 09:44:36.968 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 09:44:37.113 T:4082103200 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@:CARD=AMLM8AUDIO,DEV=0 09:44:37.113 T:4082103200 INFO: CAESinkALSA::Initialize - Attempting to open device "@:CARD=AMLM8AUDIO,DEV=0" 09:44:37.118 T:4082103200 INFO: CAESinkALSA - Unable to open device "sysdefault:CARD=AMLM8AUDIO" for playback 09:44:37.120 T:4082103200 INFO: CAESinkALSA - Unable to open device "default:CARD=AMLM8AUDIO" for playback 09:44:37.121 T:4082103200 INFO: CAESinkALSA::Initialize - Opened device "front:CARD=AMLM8AUDIO,DEV=0" 09:44:37.121 T:4082103200 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 09:44:37.121 T:4082103200 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 09:44:37.121 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 09:44:37.122 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192 09:44:37.122 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 186 ms 09:44:37.122 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 09:44:37.122 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 09:44:37.122 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: none) 09:44:37.123 T:4082103200 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 09:44:37.123 T:4082103200 DEBUG: Output Device : AML-M8AUDIO 09:44:37.123 T:4082103200 DEBUG: Sample Rate : 44100 09:44:37.123 T:4082103200 DEBUG: Sample Format : AE_FMT_S32NE 09:44:37.123 T:4082103200 DEBUG: Channel Count : 2 09:44:37.123 T:4082103200 DEBUG: Channel Layout: FL,FR 09:44:37.123 T:4082103200 DEBUG: Frames : 2048 09:44:37.123 T:4082103200 DEBUG: Frame Size : 8 09:44:37.125 T:4092289952 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 09:44:37.127 T:4114313040 DEBUG: Previous line repeats 1 times. 09:44:37.127 T:4114313040 NOTICE: VideoPlayer: OnLostDisplay received 09:44:37.127 T:4114313040 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 09:44:37.127 T:4114313040 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 09:44:37.359 T:4114313040 DEBUG: CreateWindow: Created surface of size 1920x1080 09:44:37.360 T:4114313040 NOTICE: VideoPlayer: OnResetDisplay received 09:44:37.360 T:4114313040 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 09:44:37.360 T:4114313040 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 09:44:37.376 T:4114313040 INFO: GLES: Maximum texture width: 4096 09:44:37.404 T:4114313040 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 09:44:37.405 T:4114313040 DEBUG: ------ Window Init (Home.xml) ------ 09:44:37.405 T:4114313040 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing.. 09:44:37.405 T:4114313040 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing.. 09:44:37.405 T:4114313040 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing.. 09:44:37.405 T:4114313040 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing.. 09:44:37.405 T:4114313040 DEBUG: CDirectoryProvider[pvr://channels/tv/*?view=lastplayed]: refreshing.. 09:44:37.405 T:4114313040 DEBUG: CDirectoryProvider[pvr://recordings/tv/active?view=flat]: refreshing.. 09:44:37.405 T:4114313040 DEBUG: CDirectoryProvider[pvr://channels/radio/*?view=lastplayed]: refreshing.. 09:44:37.405 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:44:37.409 T:4114313040 DEBUG: Previous line repeats 2 times. 09:44:37.410 T:4114313040 DEBUG: CDirectoryProvider[pvr://recordings/radio/active?view=flat]: refreshing.. 09:44:37.410 T:3919582112 DEBUG: Thread JobWorker start, auto delete: true 09:44:37.410 T:3919582112 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:44:37.413 T:4026524576 DEBUG: Previous line repeats 1 times. 09:44:37.413 T:4026524576 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/tv/*?view=lastplayed) 09:44:37.417 T:3919582112 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:44:37.417 T:3919582112 DEBUG: CPVRDirectory::GetDirectory(pvr://recordings/tv/active?view=flat) 09:44:37.423 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:44:37.423 T:3911193504 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/radio/*?view=lastplayed) 09:44:37.424 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:44:37.424 T:3911193504 DEBUG: CPVRDirectory::GetDirectory(pvr://recordings/radio/active?view=flat) 09:44:37.783 T:4114313040 NOTICE: CVideoPlayer::CloseFile() 09:44:37.783 T:4114313040 NOTICE: VideoPlayer: waiting for threads to exit 09:44:37.783 T:4114313040 NOTICE: VideoPlayer: finished waiting 09:44:37.783 T:4114313040 NOTICE: CVideoPlayer::CloseFile() 09:44:37.783 T:4114313040 NOTICE: VideoPlayer: waiting for threads to exit 09:44:37.783 T:4114313040 NOTICE: VideoPlayer: finished waiting 09:44:37.786 T:4114313040 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 09:44:37.788 T:3911193504 DEBUG: DoWork - Saving file state for video item removable://ANTONIAS_WELT_40ADD3BD___MVB/ 09:44:37.789 T:4026524576 INFO: easy_aquire - Created session to http://127.0.0.1 09:44:37.792 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/40 09:44:37.795 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/37 09:44:37.800 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/1 09:44:37.802 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/4 09:44:37.804 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/10 09:44:37.806 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/39 09:44:37.808 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/2 09:44:37.810 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/5 09:44:37.813 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/12 09:44:37.817 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/33 09:44:37.820 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/41 09:44:37.822 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/7 09:44:37.824 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/38 09:44:37.825 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/13 09:44:37.828 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/3 09:44:37.840 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 09:44:37.840 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 09:44:37.873 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:45:07.298 T:4114313040 DEBUG: Previous line repeats 3 times. 09:45:07.298 T:4114313040 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/VideoPlayer/libdvdnav-arm.so) 09:45:07.788 T:3919582112 DEBUG: Thread JobWorker 3919582112 terminating (autodelete) 09:45:07.810 T:4114313040 DEBUG: SECTION:UnloadDelayed(DLL: libamcodec.so) 09:45:07.828 T:4026524576 DEBUG: Thread JobWorker 4026524576 terminating (autodelete) 09:45:07.875 T:3911193504 DEBUG: Thread JobWorker 3911193504 terminating (autodelete) 09:45:08.320 T:4114313040 INFO: CheckIdle - Closing session to http://127.0.0.1 (easy=0xf2b1a530, multi=(nil)) 09:47:37.024 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnScreensaverActivated from xbmc 09:47:37.024 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnScreensaverActivated 09:47:37.026 T:4114313040 DEBUG: ------ Window Init () ------ 09:48:09.282 T:3768587168 DEBUG: CPVRTimers - Update - updating timers 09:49:26.332 T:4114313040 DEBUG: ------ Window Init (DialogNotification.xml) ------ 09:49:28.052 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:49:32.129 T:4114313040 DEBUG: Previous line repeats 7 times. 09:49:32.129 T:4114313040 DEBUG: ------ Window Deinit (DialogNotification.xml) ------ 09:53:09.317 T:3768587168 DEBUG: CPVRTimers - Update - updating timers 09:53:33.409 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:53:56.540 T:4027245472 DEBUG: Previous line repeats 7 times. ========================================================== Note: Bluray I inserted (Playback via Videos menu failed) /Videos/Files/THE_BOOK_THIEF/BDMV/MovieObject.bdmv ========================================================== 09:53:56.540 T:4027245472 INFO: Detecting DVD-ROM media filesystem... 09:53:56.554 T:4027245472 ERROR: Detection of DVD-ROM media failed. 09:53:58.976 T:3969913760 DEBUG: PushCecKeypress - received key a9 duration 0 09:53:59.007 T:4114313040 DEBUG: OnKey: 169 (0xa9, obc86) pressed, screen saver/dpms woken up 09:53:59.007 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnScreensaverDeactivated from xbmc 09:53:59.007 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnScreensaverDeactivated 09:53:59.330 T:3969913760 DEBUG: PushCecKeypress - received key a9 duration 354 09:54:00.029 T:4114313040 DEBUG: ------ Window Deinit () ------ 09:54:00.349 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:00.418 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:00.913 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:00.913 T:4082103200 INFO: CActiveAESink::OpenSink - initialize sink 09:54:00.913 T:4082103200 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@:CARD=AMLM8AUDIO,DEV=0 09:54:00.913 T:4082103200 INFO: CAESinkALSA::Initialize - Attempting to open device "@:CARD=AMLM8AUDIO,DEV=0" 09:54:00.919 T:4082103200 INFO: CAESinkALSA - Unable to open device "sysdefault:CARD=AMLM8AUDIO" for playback 09:54:00.923 T:4082103200 INFO: CAESinkALSA - Unable to open device "default:CARD=AMLM8AUDIO" for playback 09:54:00.924 T:4082103200 INFO: CAESinkALSA::Initialize - Opened device "front:CARD=AMLM8AUDIO,DEV=0" 09:54:00.925 T:4082103200 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 09:54:00.925 T:4082103200 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 09:54:00.925 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 09:54:00.930 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192 09:54:00.930 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 186 ms 09:54:00.930 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 09:54:00.931 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 09:54:00.931 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: none) 09:54:00.931 T:4082103200 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 09:54:00.931 T:4082103200 DEBUG: Output Device : AML-M8AUDIO 09:54:00.931 T:4082103200 DEBUG: Sample Rate : 44100 09:54:00.931 T:4082103200 DEBUG: Sample Format : AE_FMT_S32NE 09:54:00.931 T:4082103200 DEBUG: Channel Count : 2 09:54:00.931 T:4082103200 DEBUG: Channel Layout: FL,FR 09:54:00.931 T:4082103200 DEBUG: Frames : 2048 09:54:00.931 T:4082103200 DEBUG: Frame Size : 8 09:54:01.218 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:01.228 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:01.287 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:01.785 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:01.803 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:01.854 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:03.240 T:3969913760 DEBUG: PushCecKeypress - received key a9 duration 0 09:54:03.260 T:4114313040 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 09:54:03.309 T:3969913760 DEBUG: PushCecKeypress - received key a9 duration 69 09:54:04.289 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:04.303 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:04.304 T:4114313040 DEBUG: CDirectoryProvider[pvr://recordings/tv/active?view=flat]: refreshing.. 09:54:04.305 T:3911193504 DEBUG: Thread JobWorker start, auto delete: true 09:54:04.305 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:04.305 T:3911193504 DEBUG: CPVRDirectory::GetDirectory(pvr://recordings/tv/active?view=flat) 09:54:04.316 T:4026524576 DEBUG: Thread JobWorker start, auto delete: true 09:54:04.317 T:4026524576 INFO: easy_aquire - Created session to http://127.0.0.1 09:54:04.317 T:3919582112 DEBUG: Thread JobWorker start, auto delete: true 09:54:04.318 T:3919582112 DEBUG: Previous line repeats 1 times. 09:54:04.318 T:3919582112 INFO: easy_aquire - Created session to http://127.0.0.1 09:54:04.320 T:3663721376 INFO: Previous line repeats 1 times. 09:54:04.320 T:3663721376 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/1 09:54:04.320 T:3919582112 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/37 09:54:04.322 T:3919582112 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/10 09:54:04.322 T:3663721376 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/4 09:54:04.324 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/40 09:54:04.333 T:3919582112 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/39 09:54:04.357 T:3663721376 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/10 09:54:04.358 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:04.361 T:3663721376 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/2 09:54:04.365 T:3911193504 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/8 09:54:04.846 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:04.852 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:04.915 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:05.165 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:05.166 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:05.234 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:05.462 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:05.465 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:05.531 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:05.986 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:05.996 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:06.055 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:07.977 T:3969913760 DEBUG: PushCecKeypress - received key a8 duration 0 09:54:07.994 T:4114313040 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 09:54:08.046 T:3969913760 DEBUG: PushCecKeypress - received key a8 duration 69 09:54:11.438 T:3969913760 DEBUG: PushCecKeypress - received key b duration 0 09:54:11.476 T:4114313040 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 09:54:11.476 T:4114313040 DEBUG: Activating window ID: 10025 09:54:11.507 T:3969913760 DEBUG: PushCecKeypress - received key b duration 68 09:54:11.796 T:4114313040 DEBUG: ------ Window Deinit (Home.xml) ------ 09:54:11.805 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:11.809 T:4114313040 DEBUG: Previous line repeats 6 times. 09:54:11.809 T:4114313040 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 09:54:11.809 T:4114313040 INFO: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY 09:54:11.946 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:11.961 T:4114313040 DEBUG: Previous line repeats 6 times. 09:54:11.961 T:4114313040 DEBUG: CDirectoryProvider[]: refreshing.. 09:54:11.962 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:11.962 T:3911193504 ERROR: GetDirectory - Error getting 09:54:11.965 T:4114313040 DEBUG: CDirectoryProvider[]: refreshing.. 09:54:11.965 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:11.965 T:3911193504 ERROR: GetDirectory - Error getting 09:54:11.965 T:4114313040 DEBUG: CDirectoryProvider[]: refreshing.. 09:54:11.965 T:3911193504 DEBUG: Previous line repeats 1 times. 09:54:11.965 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:11.965 T:3911193504 ERROR: GetDirectory - Error getting 09:54:11.965 T:4114313040 DEBUG: CDirectoryProvider[]: refreshing.. 09:54:11.965 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:11.965 T:3911193504 ERROR: GetDirectory - Error getting 09:54:11.965 T:4114313040 DEBUG: CDirectoryProvider[]: refreshing.. 09:54:11.965 T:3919582112 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:11.965 T:3919582112 ERROR: GetDirectory - Error getting 09:54:11.965 T:4114313040 DEBUG: CDirectoryProvider[]: refreshing.. 09:54:11.965 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:11.965 T:3911193504 ERROR: GetDirectory - Error getting 09:54:11.970 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:11.970 T:4114313040 DEBUG: CGUIMediaWindow::GetDirectory (library://video/files.xml/) 09:54:11.970 T:3911193504 ERROR: GetDirectory - Error getting 09:54:11.970 T:4114313040 DEBUG: ParentPath = [library://video/files.xml/] 09:54:11.970 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:11.983 T:3706184608 DEBUG: Previous line repeats 3 times. 09:54:11.983 T:3706184608 DEBUG: Thread BackgroundLoader start, auto delete: false 09:54:11.988 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:11.992 T:3706184608 DEBUG: Previous line repeats 6 times. 09:54:11.992 T:3706184608 DEBUG: Thread BackgroundLoader 3706184608 terminating 09:54:14.395 T:3969913760 DEBUG: PushCecKeypress - received key a8 duration 0 09:54:14.413 T:4114313040 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 09:54:14.464 T:3969913760 DEBUG: PushCecKeypress - received key a8 duration 69 09:54:16.321 T:3969913760 DEBUG: PushCecKeypress - received key b duration 0 09:54:16.321 T:4114313040 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 09:54:16.322 T:4114313040 DEBUG: CGUIMediaWindow::GetDirectory (/var/media/The Book Thief/) 09:54:16.322 T:4114313040 DEBUG: ParentPath = [sources://video/] 09:54:16.322 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:16.389 T:3969913760 DEBUG: PushCecKeypress - received key b duration 69 09:54:16.548 T:3706184608 DEBUG: Thread BackgroundLoader start, auto delete: false 09:54:16.872 T:3706184608 DEBUG: Thread BackgroundLoader 3706184608 terminating 09:54:18.117 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:18.127 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:18.186 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:18.587 T:3969913760 DEBUG: PushCecKeypress - received key b duration 0 09:54:18.605 T:4114313040 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 09:54:18.605 T:4114313040 DEBUG: CGUIMediaWindow::GetDirectory (/var/media/The Book Thief/AACS/) 09:54:18.605 T:4114313040 DEBUG: ParentPath = [/var/media/The Book Thief/] 09:54:18.605 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:18.656 T:3969913760 DEBUG: PushCecKeypress - received key b duration 69 09:54:18.745 T:3706184608 DEBUG: Thread BackgroundLoader start, auto delete: false 09:54:18.759 T:3706184608 DEBUG: Thread BackgroundLoader 3706184608 terminating 09:54:19.996 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:20.017 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:20.065 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:21.145 T:3969913760 DEBUG: PushCecKeypress - received key a6 duration 0 09:54:21.158 T:4114313040 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up 09:54:21.214 T:3969913760 DEBUG: PushCecKeypress - received key a6 duration 69 09:54:21.614 T:3969913760 DEBUG: PushCecKeypress - received key b duration 0 09:54:21.633 T:4114313040 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 09:54:21.633 T:4114313040 DEBUG: CGUIMediaWindow::GetDirectory (/var/media/The Book Thief/) 09:54:21.633 T:4114313040 DEBUG: ParentPath = [sources://video/] 09:54:21.634 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:21.638 T:3706184608 DEBUG: Thread BackgroundLoader start, auto delete: false 09:54:21.654 T:3706184608 DEBUG: Thread BackgroundLoader 3706184608 terminating 09:54:21.683 T:3969913760 DEBUG: PushCecKeypress - received key b duration 69 09:54:22.457 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:22.493 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:22.526 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:23.217 T:3969913760 DEBUG: PushCecKeypress - received key b duration 0 09:54:23.235 T:4114313040 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 09:54:23.236 T:4114313040 DEBUG: CGUIMediaWindow::GetDirectory (/var/media/The Book Thief/BDMV/) 09:54:23.236 T:4114313040 DEBUG: ParentPath = [/var/media/The Book Thief/] 09:54:23.236 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:23.286 T:3969913760 DEBUG: PushCecKeypress - received key b duration 69 09:54:23.701 T:4114313040 DEBUG: RunQuery took 0 ms for 2 items query: SELECT files.strFilename, files.playCount, bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files LEFT JOIN bookmark ON files.idFile = bookmark.idFile AND bookmark.type = 1 WHERE files.idPath=18 09:54:23.702 T:3706184608 DEBUG: Thread BackgroundLoader start, auto delete: false 09:54:24.214 T:3706184608 DEBUG: Thread BackgroundLoader 3706184608 terminating 09:54:25.138 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:25.164 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:25.207 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:25.474 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:25.514 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:25.543 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:25.718 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:25.739 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:25.787 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:25.969 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:25.978 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:26.038 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:26.220 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:26.246 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:26.289 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:26.552 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:26.555 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:26.620 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:26.803 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:26.824 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:26.872 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:27.054 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:27.094 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:27.123 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:27.305 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:54:27.321 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:54:27.374 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:54:29.801 T:3969913760 DEBUG: PushCecKeypress - received key b duration 0 09:54:29.813 T:4114313040 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 09:54:29.820 T:4114313040 DEBUG: OnPlayMedia /var/media/The Book Thief/BDMV/index.bdmv 09:54:29.820 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 09:54:29.820 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 09:54:29.820 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:29.831 T:4114297760 DEBUG: Previous line repeats 1 times. 09:54:29.831 T:4114297760 DEBUG: GetMovieId (/var/media/The Book Thief/BDMV/index.bdmv), query = select idMovie from movie where idFile=36 09:54:29.831 T:4114313040 DEBUG: Loading settings for /var/media/The Book Thief/BDMV/index.bdmv 09:54:29.832 T:4114297760 DEBUG: GetEpisodeId (/var/media/The Book Thief/BDMV/index.bdmv), query = select idEpisode from episode where idFile=36 09:54:29.832 T:4114297760 DEBUG: GetMusicVideoId (/var/media/The Book Thief/BDMV/index.bdmv), query = select idMVideo from musicvideo where idFile=36 09:54:29.835 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 09:54:29.835 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 09:54:29.839 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers(/var/media/The Book Thief/BDMV/index.bdmv) 09:54:29.839 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 09:54:29.839 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 09:54:29.839 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 09:54:29.840 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 09:54:29.840 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 09:54:29.840 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 09:54:29.840 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 09:54:29.840 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 09:54:29.840 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 09:54:29.840 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 09:54:29.840 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 09:54:29.840 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 09:54:29.840 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 09:54:29.840 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 09:54:29.840 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 09:54:29.840 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 09:54:29.840 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 09:54:29.843 T:4114313040 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData 09:54:29.843 T:4114313040 NOTICE: VideoPlayer: Opening: /var/media/The Book Thief/BDMV/index.bdmv 09:54:29.843 T:4114313040 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 09:54:29.843 T:4114313040 DEBUG: LinuxRendererGL: Cleaning up GL resources 09:54:29.844 T:3706184608 DEBUG: Thread VideoPlayer start, auto delete: false 09:54:29.844 T:3706184608 NOTICE: Creating InputStream 09:54:29.844 T:3706184608 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:29.845 T:3706184608 DEBUG: Previous line repeats 1 times. 09:54:29.845 T:3706184608 DEBUG: SECTION:LoadDLL(libbluray.so.1) 09:54:29.846 T:3706184608 DEBUG: Loading: libbluray.so.1 09:54:29.853 T:3706184608 DEBUG: CDVDInputStreamBluray::Open - opening /var/media/The Book Thief 09:54:29.853 T:3706184608 DEBUG: CDVDInputStreamBluray::Logger - bluray.c:1369: libbluray version 0.9.3 09:54:29.853 T:3706184608 DEBUG: CDVDInputStreamBluray::Logger - bluray.c:1390: BLURAY initialized! 09:54:29.853 T:3706184608 DEBUG: CDVDInputStreamBluray - Opening dir /var/media/The Book Thief 09:54:29.853 T:3706184608 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:29.854 T:3706184608 DEBUG: CDVDInputStreamBluray - Closed dir (0xee626130) 09:54:29.854 T:3706184608 DEBUG: CDVDInputStreamBluray::Logger - aacs.c:78: AACS/Unit_Key_RO.inf found. Disc seems to be AACS protected. 09:54:29.855 T:3706184608 DEBUG: CDVDInputStreamBluray::Logger - bdplus.c:82: BDSVM/00000.svm found. Disc seems to be BD+ protected. 09:54:29.864 T:3706184608 DEBUG: CDVDInputStreamBluray::Logger - bdplus.c:108: Using libbdplus for BD+ 09:54:29.864 T:3706184608 DEBUG: CDVDInputStreamBluray::Logger - bdplus.c:139: Loading libbdplus (0xee636938) 09:54:29.864 T:3706184608 DEBUG: CDVDInputStreamBluray::Logger - bdplus.c:160: Loaded libbdplus (0xee636938) 09:54:29.866 T:3706184608 DEBUG: CDVDInputStreamBluray::Logger - aacs.c:104: Using libaacs for AACS 09:54:29.866 T:3706184608 DEBUG: CDVDInputStreamBluray::Logger - aacs.c:128: Loading aacs library (0xee601590) 09:54:29.866 T:3706184608 DEBUG: CDVDInputStreamBluray::Logger - aacs.c:139: Loaded libaacs (0xee601590) 09:54:29.867 T:3706184608 DEBUG: CDVDInputStreamBluray::Logger - aacs.c:142: Registering libaacs filesystem handler 0x48459c (0xee601590) 09:54:29.870 T:3969913760 DEBUG: PushCecKeypress - received key b duration 68 09:54:30.344 T:4114313040 DEBUG: ------ Window Init (DialogBusy.xml) ------ 09:54:31.043 T:3706184608 DEBUG: CDVDInputStreamBluray::Logger - dec.c:202: Opened libaacs 09:54:31.439 T:3706184608 DEBUG: CDVDInputStreamBluray::Logger - bdplus.c:216: bdplus_init() failed! (0xee636938) 09:54:31.439 T:3706184608 DEBUG: CDVDInputStreamBluray::Logger - dec.c:223: bdplus_init() failed 09:54:31.557 T:3706184608 DEBUG: CDVDInputStreamBluray - Opening dir /var/media/The Book Thief/BDMV/META/DL 09:54:31.557 T:3706184608 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:31.704 T:3706184608 DEBUG: CDVDInputStreamBluray - Closed dir (0xee6042d8) 09:54:32.108 T:3706184608 DEBUG: CDVDInputStreamBluray::Open - First Play supported: 0 09:54:32.108 T:3706184608 DEBUG: CDVDInputStreamBluray::Open - Top menu supported : 0 09:54:32.108 T:3706184608 DEBUG: CDVDInputStreamBluray::Open - HDMV titles : 5 09:54:32.108 T:3706184608 DEBUG: CDVDInputStreamBluray::Open - BD-J titles : 86 09:54:32.108 T:3706184608 DEBUG: CDVDInputStreamBluray::Open - UNSUPPORTED titles : 86 09:54:32.108 T:3706184608 DEBUG: CDVDInputStreamBluray::Open - AACS detected : 1 09:54:32.108 T:3706184608 DEBUG: CDVDInputStreamBluray::Open - libaacs detected : 1 09:54:32.108 T:3706184608 DEBUG: CDVDInputStreamBluray::Open - AACS handled : 1 09:54:32.108 T:3706184608 DEBUG: CDVDInputStreamBluray::Open - BD+ detected : 1 09:54:32.108 T:3706184608 DEBUG: CDVDInputStreamBluray::Open - libbdplus detected : 1 09:54:32.108 T:3706184608 DEBUG: CDVDInputStreamBluray::Open - BD+ handled : 0 09:54:32.108 T:3706184608 ERROR: CDVDInputStreamBluray::Open - Media stream scrambled/encrypted with BD+ 09:54:32.108 T:3706184608 ERROR: CVideoPlayer::OpenInputStream - error opening [/var/media/The Book Thief/BDMV/index.bdmv] 09:54:32.108 T:3706184608 NOTICE: CVideoPlayer::OnExit() 09:54:32.109 T:3706184608 DEBUG: CDVDInputStreamBluray::Logger - bluray.c:1507: BLURAY destroyed! 09:54:32.109 T:3706184608 DEBUG: OnPlayBackStopped: play state was 1, starting 1 09:54:32.109 T:3706184608 DEBUG: Thread VideoPlayer 3706184608 terminating 09:54:32.113 T:4114313040 DEBUG: OnPlayBackStopped: play state was 3, starting 0 09:54:32.113 T:4114313040 ERROR: Playlist Player: skipping unplayable item: 0, path [/var/media/The Book Thief/BDMV/index.bdmv] 09:54:32.113 T:4114313040 DEBUG: Playlist Player: no more playable items... aborting playback 09:54:32.114 T:3706184608 DEBUG: Thread BackgroundLoader start, auto delete: false 09:54:32.120 T:4114297760 DEBUG: GetMovieId (/var/media/The Book Thief/BDMV/index.bdmv), query = select idMovie from movie where idFile=36 09:54:32.121 T:4114297760 DEBUG: GetEpisodeId (/var/media/The Book Thief/BDMV/index.bdmv), query = select idEpisode from episode where idFile=36 09:54:32.121 T:4114297760 DEBUG: GetMusicVideoId (/var/media/The Book Thief/BDMV/index.bdmv), query = select idMVideo from musicvideo where idFile=36 09:54:32.124 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 09:54:32.124 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 09:54:32.130 T:4114313040 NOTICE: CVideoPlayer::CloseFile() 09:54:32.130 T:4114313040 NOTICE: VideoPlayer: waiting for threads to exit 09:54:32.130 T:4114313040 NOTICE: VideoPlayer: finished waiting 09:54:32.130 T:4114313040 DEBUG: DeleteRenderer - deleting renderer 09:54:32.130 T:4114313040 DEBUG: LinuxRendererGL: Cleaning up GL resources 09:54:32.130 T:4114313040 NOTICE: CVideoPlayer::CloseFile() 09:54:32.130 T:4114313040 NOTICE: VideoPlayer: waiting for threads to exit 09:54:32.130 T:4114313040 NOTICE: VideoPlayer: finished waiting 09:54:32.132 T:4114313040 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 09:54:32.147 T:3706184608 DEBUG: Thread BackgroundLoader 3706184608 terminating 09:54:32.363 T:4114313040 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 09:54:32.396 T:3663721376 DEBUG: DoWork - Saving file state for video item /var/media/The Book Thief/BDMV/index.bdmv 09:54:32.442 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 09:54:32.442 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 09:54:32.443 T:3663721376 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:54:34.502 T:4114313040 DEBUG: Previous line repeats 3 times. 09:54:34.502 T:4114313040 INFO: CheckIdle - Closing session to http://127.0.0.1 (easy=0xee65bab0, multi=(nil)) 09:54:34.502 T:4114313040 INFO: CheckIdle - Closing session to http://127.0.0.1 (easy=0xda668c20, multi=(nil)) 09:54:34.502 T:4114313040 INFO: CheckIdle - Closing session to http://127.0.0.1 (easy=0xf2b18790, multi=(nil)) 09:55:02.254 T:4114313040 DEBUG: SECTION:UnloadDelayed(DLL: libbluray.so.1) 09:55:02.400 T:3919582112 DEBUG: Thread JobWorker 3919582112 terminating (autodelete) 09:55:02.400 T:3911193504 DEBUG: Thread JobWorker 3911193504 terminating (autodelete) 09:55:02.401 T:4026524576 DEBUG: Thread JobWorker 4026524576 terminating (autodelete) 09:55:02.445 T:3663721376 DEBUG: Thread JobWorker 3663721376 terminating (autodelete) 09:55:57.008 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 09:55:57.015 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 09:55:57.016 T:4082103200 INFO: CActiveAESink::OpenSink - initialize sink 09:55:57.016 T:4082103200 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@:CARD=AMLM8AUDIO,DEV=0 09:55:57.016 T:4082103200 INFO: CAESinkALSA::Initialize - Attempting to open device "@:CARD=AMLM8AUDIO,DEV=0" 09:55:57.023 T:4082103200 INFO: CAESinkALSA - Unable to open device "sysdefault:CARD=AMLM8AUDIO" for playback 09:55:57.025 T:4082103200 INFO: CAESinkALSA - Unable to open device "default:CARD=AMLM8AUDIO" for playback 09:55:57.026 T:4082103200 INFO: CAESinkALSA::Initialize - Opened device "front:CARD=AMLM8AUDIO,DEV=0" 09:55:57.027 T:4082103200 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 09:55:57.027 T:4082103200 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 09:55:57.027 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 09:55:57.033 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192 09:55:57.033 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 186 ms 09:55:57.033 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 09:55:57.033 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 09:55:57.033 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: none) 09:55:57.034 T:4082103200 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 09:55:57.034 T:4082103200 DEBUG: Output Device : AML-M8AUDIO 09:55:57.034 T:4082103200 DEBUG: Sample Rate : 44100 09:55:57.034 T:4082103200 DEBUG: Sample Format : AE_FMT_S32NE 09:55:57.034 T:4082103200 DEBUG: Channel Count : 2 09:55:57.034 T:4082103200 DEBUG: Channel Layout: FL,FR 09:55:57.034 T:4082103200 DEBUG: Frames : 2048 09:55:57.034 T:4082103200 DEBUG: Frame Size : 8 09:55:57.077 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 09:55:57.865 T:3969913760 DEBUG: PushCecKeypress - received key b duration 0 09:55:57.895 T:4114313040 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 09:55:57.902 T:4114313040 DEBUG: OnPlayMedia /var/media/The Book Thief/BDMV/MovieObject.bdmv 09:55:57.903 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 09:55:57.903 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 09:55:57.903 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:55:57.914 T:4114313040 DEBUG: Previous line repeats 1 times. 09:55:57.914 T:4114313040 DEBUG: Loading settings for /var/media/The Book Thief/BDMV/MovieObject.bdmv 09:55:57.914 T:4114297760 DEBUG: GetMovieId (/var/media/The Book Thief/BDMV/MovieObject.bdmv), query = select idMovie from movie where idFile=37 09:55:57.914 T:4114297760 DEBUG: GetEpisodeId (/var/media/The Book Thief/BDMV/MovieObject.bdmv), query = select idEpisode from episode where idFile=37 09:55:57.915 T:4114297760 DEBUG: GetMusicVideoId (/var/media/The Book Thief/BDMV/MovieObject.bdmv), query = select idMVideo from musicvideo where idFile=37 09:55:57.918 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 09:55:57.918 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 09:55:57.923 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers(/var/media/The Book Thief/BDMV/MovieObject.bdmv) 09:55:57.923 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 09:55:57.923 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 09:55:57.923 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 09:55:57.923 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 09:55:57.923 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 09:55:57.923 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 09:55:57.923 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 09:55:57.923 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 09:55:57.923 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 09:55:57.923 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 09:55:57.923 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 09:55:57.923 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 09:55:57.923 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 09:55:57.923 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 09:55:57.923 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 09:55:57.923 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 09:55:57.923 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 09:55:57.926 T:4114313040 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData 09:55:57.926 T:4114313040 NOTICE: VideoPlayer: Opening: /var/media/The Book Thief/BDMV/MovieObject.bdmv 09:55:57.927 T:4114313040 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 09:55:57.927 T:4114313040 DEBUG: LinuxRendererGL: Cleaning up GL resources 09:55:57.927 T:3663721376 DEBUG: Thread VideoPlayer start, auto delete: false 09:55:57.927 T:3663721376 NOTICE: Creating InputStream 09:55:57.927 T:3663721376 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:55:57.928 T:3663721376 DEBUG: Previous line repeats 1 times. 09:55:57.928 T:3663721376 DEBUG: SECTION:LoadDLL(libbluray.so.1) 09:55:57.929 T:3663721376 DEBUG: Loading: libbluray.so.1 09:55:57.930 T:3663721376 DEBUG: CDVDInputStreamBluray::Open - opening /var/media/The Book Thief 09:55:57.930 T:3663721376 DEBUG: CDVDInputStreamBluray::Logger - bluray.c:1369: libbluray version 0.9.3 09:55:57.930 T:3663721376 DEBUG: CDVDInputStreamBluray::Logger - bluray.c:1390: BLURAY initialized! 09:55:57.930 T:3663721376 DEBUG: CDVDInputStreamBluray - Opening dir /var/media/The Book Thief 09:55:57.930 T:3663721376 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:55:57.931 T:3663721376 DEBUG: CDVDInputStreamBluray - Closed dir (0xf2b06af0) 09:55:57.931 T:3663721376 DEBUG: CDVDInputStreamBluray::Logger - aacs.c:78: AACS/Unit_Key_RO.inf found. Disc seems to be AACS protected. 09:55:57.931 T:3663721376 DEBUG: CDVDInputStreamBluray::Logger - bdplus.c:82: BDSVM/00000.svm found. Disc seems to be BD+ protected. 09:55:57.932 T:3663721376 DEBUG: CDVDInputStreamBluray::Logger - bdplus.c:108: Using libbdplus for BD+ 09:55:57.932 T:3663721376 DEBUG: CDVDInputStreamBluray::Logger - bdplus.c:139: Loading libbdplus (0xf2b16dc0) 09:55:57.932 T:3663721376 DEBUG: CDVDInputStreamBluray::Logger - bdplus.c:160: Loaded libbdplus (0xf2b16dc0) 09:55:57.932 T:3663721376 DEBUG: CDVDInputStreamBluray::Logger - aacs.c:104: Using libaacs for AACS 09:55:57.932 T:3663721376 DEBUG: CDVDInputStreamBluray::Logger - aacs.c:128: Loading aacs library (0xf2b09e80) 09:55:57.932 T:3663721376 DEBUG: CDVDInputStreamBluray::Logger - aacs.c:139: Loaded libaacs (0xf2b09e80) 09:55:57.932 T:3663721376 DEBUG: CDVDInputStreamBluray::Logger - aacs.c:142: Registering libaacs filesystem handler 0x48459c (0xf2b09e80) 09:55:57.934 T:3969913760 DEBUG: PushCecKeypress - received key b duration 69 09:55:58.415 T:3663721376 DEBUG: CDVDInputStreamBluray::Logger - dec.c:202: Opened libaacs 09:55:58.427 T:4114313040 DEBUG: ------ Window Init (DialogBusy.xml) ------ 09:55:58.803 T:3663721376 DEBUG: CDVDInputStreamBluray::Logger - bdplus.c:216: bdplus_init() failed! (0xf2b16dc0) 09:55:58.804 T:3663721376 DEBUG: CDVDInputStreamBluray::Logger - dec.c:223: bdplus_init() failed 09:55:58.804 T:3663721376 DEBUG: CDVDInputStreamBluray - Opening dir /var/media/The Book Thief/BDMV/META/DL 09:55:58.804 T:3663721376 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:55:58.805 T:3663721376 DEBUG: CDVDInputStreamBluray - Closed dir (0xf2b02e10) 09:55:58.807 T:3663721376 DEBUG: CDVDInputStreamBluray::Open - First Play supported: 0 09:55:58.807 T:3663721376 DEBUG: CDVDInputStreamBluray::Open - Top menu supported : 0 09:55:58.807 T:3663721376 DEBUG: CDVDInputStreamBluray::Open - HDMV titles : 5 09:55:58.807 T:3663721376 DEBUG: CDVDInputStreamBluray::Open - BD-J titles : 86 09:55:58.807 T:3663721376 DEBUG: CDVDInputStreamBluray::Open - UNSUPPORTED titles : 86 09:55:58.807 T:3663721376 DEBUG: CDVDInputStreamBluray::Open - AACS detected : 1 09:55:58.807 T:3663721376 DEBUG: CDVDInputStreamBluray::Open - libaacs detected : 1 09:55:58.807 T:3663721376 DEBUG: CDVDInputStreamBluray::Open - AACS handled : 1 09:55:58.807 T:3663721376 DEBUG: CDVDInputStreamBluray::Open - BD+ detected : 1 09:55:58.807 T:3663721376 DEBUG: CDVDInputStreamBluray::Open - libbdplus detected : 1 09:55:58.808 T:3663721376 DEBUG: CDVDInputStreamBluray::Open - BD+ handled : 0 09:55:58.808 T:3663721376 ERROR: CDVDInputStreamBluray::Open - Media stream scrambled/encrypted with BD+ 09:55:58.808 T:3663721376 ERROR: CVideoPlayer::OpenInputStream - error opening [/var/media/The Book Thief/BDMV/MovieObject.bdmv] 09:55:58.808 T:3663721376 NOTICE: CVideoPlayer::OnExit() 09:55:58.808 T:3663721376 DEBUG: CDVDInputStreamBluray::Logger - bluray.c:1507: BLURAY destroyed! 09:55:58.808 T:3663721376 DEBUG: OnPlayBackStopped: play state was 1, starting 1 09:55:58.808 T:3663721376 DEBUG: Thread VideoPlayer 3663721376 terminating 09:55:58.814 T:4114313040 DEBUG: OnPlayBackStopped: play state was 3, starting 0 09:55:58.814 T:4114313040 ERROR: Playlist Player: skipping unplayable item: 0, path [/var/media/The Book Thief/BDMV/MovieObject.bdmv] 09:55:58.814 T:4114313040 DEBUG: Playlist Player: one or more items failed to play... aborting playback 09:55:58.814 T:4114313040 DEBUG: ------ Window Init (DialogConfirm.xml) ------ 09:55:58.814 T:4114313040 INFO: Loading skin file: DialogConfirm.xml, load type: KEEP_IN_MEMORY 09:55:58.820 T:4114313040 NOTICE: CVideoPlayer::CloseFile() 09:55:58.820 T:4114313040 NOTICE: VideoPlayer: waiting for threads to exit 09:55:58.820 T:4114313040 NOTICE: VideoPlayer: finished waiting 09:55:58.820 T:4114313040 DEBUG: DeleteRenderer - deleting renderer 09:55:58.820 T:4114313040 DEBUG: LinuxRendererGL: Cleaning up GL resources 09:55:58.820 T:4114313040 NOTICE: CVideoPlayer::CloseFile() 09:55:58.820 T:4114313040 NOTICE: VideoPlayer: waiting for threads to exit 09:55:58.820 T:4114313040 NOTICE: VideoPlayer: finished waiting 09:55:58.822 T:4114297760 DEBUG: GetMovieId (/var/media/The Book Thief/BDMV/MovieObject.bdmv), query = select idMovie from movie where idFile=37 09:55:58.822 T:4114313040 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 09:55:58.822 T:4114297760 DEBUG: GetEpisodeId (/var/media/The Book Thief/BDMV/MovieObject.bdmv), query = select idEpisode from episode where idFile=37 09:55:58.823 T:4114297760 DEBUG: GetMusicVideoId (/var/media/The Book Thief/BDMV/MovieObject.bdmv), query = select idMVideo from musicvideo where idFile=37 09:55:58.825 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 09:55:58.825 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 09:55:58.930 T:3663721376 DEBUG: Thread JobWorker start, auto delete: true 09:55:58.930 T:3663721376 DEBUG: DoWork - Saving file state for video item /var/media/The Book Thief/BDMV/MovieObject.bdmv 09:55:58.976 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 09:55:58.977 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 09:55:58.977 T:3663721376 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:55:59.197 T:4114313040 DEBUG: Previous line repeats 3 times. 09:55:59.197 T:4114313040 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 09:56:02.278 T:3969913760 DEBUG: PushCecKeypress - received key b duration 0 09:56:02.279 T:4114313040 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 09:56:02.347 T:3969913760 DEBUG: PushCecKeypress - received key b duration 69 09:56:02.547 T:4114313040 DEBUG: ------ Window Deinit (DialogConfirm.xml) ------ 09:56:02.547 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 09:56:02.547 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 09:56:02.548 T:4026524576 DEBUG: Thread BackgroundLoader start, auto delete: false 09:56:02.576 T:4026524576 DEBUG: Thread BackgroundLoader 4026524576 terminating 09:56:29.257 T:4114313040 DEBUG: SECTION:UnloadDelayed(DLL: libbluray.so.1) 09:56:32.575 T:3663721376 DEBUG: Thread JobWorker 3663721376 terminating (autodelete) 09:58:08.362 T:3663721376 DEBUG: Thread JobWorker start, auto delete: true 09:58:08.362 T:3663721376 INFO: WEATHER: Downloading weather 09:58:08.362 T:4026524576 DEBUG: Thread LanguageInvoker start, auto delete: false 09:58:08.362 T:4026524576 INFO: initializing python engine. 09:58:08.362 T:4026524576 DEBUG: CPythonInvoker(3, /storage/.kodi/addons/weather.openweathermap.extended/default.py): start processing 09:58:08.386 T:4026524576 DEBUG: -->Python Interpreter Initialized<-- 09:58:08.386 T:4026524576 DEBUG: CPythonInvoker(3, /storage/.kodi/addons/weather.openweathermap.extended/default.py): the source file to load is "/storage/.kodi/addons/weather.openweathermap.extended/default.py" 09:58:08.386 T:4026524576 DEBUG: CPythonInvoker(3, /storage/.kodi/addons/weather.openweathermap.extended/default.py): setting the Python path to /storage/.kodi/addons/weather.openweathermap.extended:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages/gtk-2.0 09:58:08.386 T:4026524576 DEBUG: CPythonInvoker(3, /storage/.kodi/addons/weather.openweathermap.extended/default.py): entering source directory /storage/.kodi/addons/weather.openweathermap.extended 09:58:08.386 T:4026524576 DEBUG: CPythonInvoker(3, /storage/.kodi/addons/weather.openweathermap.extended/default.py): instantiating addon using automatically obtained id of "weather.openweathermap.extended" dependent on version 2.25.0 of the xbmc.python api 09:58:08.522 T:4026524576 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 09:58:08.647 T:4026524576 DEBUG: Previous line repeats 40 times. 09:58:08.647 T:4026524576 INFO: CPythonInvoker(3, /storage/.kodi/addons/weather.openweathermap.extended/default.py): script successfully run 09:58:08.687 T:4026524576 INFO: Python script stopped 09:58:08.687 T:4026524576 DEBUG: Thread LanguageInvoker 4026524576 terminating 09:58:08.774 T:3663721376 DEBUG: POParser: loaded 130 weather tokens 09:58:09.353 T:3768587168 DEBUG: CPVRTimers - Update - updating timers 09:58:38.775 T:3663721376 DEBUG: Thread JobWorker 3663721376 terminating (autodelete) 09:59:02.738 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnScreensaverActivated from xbmc 09:59:02.738 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnScreensaverActivated 09:59:02.740 T:4114313040 DEBUG: ------ Window Init () ------ 10:01:51.900 T:4114313040 DEBUG: ------ Window Init (DialogNotification.xml) ------ 10:01:52.297 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:01:57.696 T:4114313040 DEBUG: Previous line repeats 7 times. 10:01:57.697 T:4114313040 DEBUG: ------ Window Deinit (DialogNotification.xml) ------ =========================================================== Note: Bluray II inserted (Playback via Videos menu failed) /Videos/Files/NO_TURNING_BACK/BDMV/MovieObject.bdmv =========================================================== 10:02:27.299 T:4027245472 INFO: Detecting DVD-ROM media filesystem... 10:02:27.314 T:4027245472 ERROR: Detection of DVD-ROM media failed. 10:03:00.204 T:3969913760 DEBUG: PushCecKeypress - received key d8 duration 0 10:03:00.224 T:4114313040 DEBUG: OnKey: menu (0xd8) pressed, screen saver/dpms woken up 10:03:00.224 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnScreensaverDeactivated from xbmc 10:03:00.224 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnScreensaverDeactivated 10:03:00.560 T:3969913760 DEBUG: PushCecKeypress - received key d8 duration 356 10:03:01.136 T:3969913760 DEBUG: PushCecKeypress - received key d8 duration 0 10:03:01.139 T:4114313040 DEBUG: OnKey: menu (0xd8) pressed, action is Back 10:03:01.139 T:4114313040 DEBUG: CGUIMediaWindow::GetDirectory (/var/media/The Book Thief/) 10:03:01.139 T:4114313040 DEBUG: ParentPath = [sources://video/] 10:03:01.139 T:4082103200 INFO: CActiveAESink::OpenSink - initialize sink 10:03:01.139 T:4082103200 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@:CARD=AMLM8AUDIO,DEV=0 10:03:01.140 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:03:01.140 T:4082103200 INFO: CAESinkALSA::Initialize - Attempting to open device "@:CARD=AMLM8AUDIO,DEV=0" 10:03:01.140 T:3663721376 DEBUG: Thread JobWorker start, auto delete: true 10:03:01.140 T:4114313040 ERROR: GetDirectory - Error getting /var/media/The Book Thief/ 10:03:01.141 T:4114313040 ERROR: CGUIMediaWindow::GetDirectory(/var/media/The Book Thief/) failed 10:03:01.141 T:4114313040 DEBUG: CGUIMediaWindow::GetDirectory (sources://video/) 10:03:01.141 T:4114313040 DEBUG: ParentPath = [] 10:03:01.142 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:03:01.146 T:4082103200 INFO: CAESinkALSA - Unable to open device "sysdefault:CARD=AMLM8AUDIO" for playback 10:03:01.148 T:4082103200 INFO: CAESinkALSA - Unable to open device "default:CARD=AMLM8AUDIO" for playback 10:03:01.149 T:4082103200 INFO: CAESinkALSA::Initialize - Opened device "front:CARD=AMLM8AUDIO,DEV=0" 10:03:01.149 T:4082103200 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 10:03:01.150 T:4082103200 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 10:03:01.150 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 10:03:01.156 T:4026524576 DEBUG: Thread BackgroundLoader start, auto delete: false 10:03:01.159 T:4114313040 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 10:03:01.160 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192 10:03:01.160 T:4082103200 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 186 ms 10:03:01.160 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 10:03:01.160 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 10:03:01.160 T:4082103200 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: none) 10:03:01.160 T:4082103200 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 10:03:01.160 T:4082103200 DEBUG: Output Device : AML-M8AUDIO 10:03:01.160 T:4082103200 DEBUG: Sample Rate : 44100 10:03:01.161 T:4082103200 DEBUG: Sample Format : AE_FMT_S32NE 10:03:01.161 T:4082103200 DEBUG: Channel Count : 2 10:03:01.161 T:4082103200 DEBUG: Channel Layout: FL,FR 10:03:01.161 T:4082103200 DEBUG: Frames : 2048 10:03:01.161 T:4082103200 DEBUG: Frame Size : 8 10:03:01.166 T:4026524576 DEBUG: Thread BackgroundLoader 4026524576 terminating 10:03:01.205 T:3969913760 DEBUG: PushCecKeypress - received key d8 duration 69 10:03:01.255 T:4114313040 DEBUG: ------ Window Deinit () ------ 10:03:01.454 T:4114313040 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 10:03:01.455 T:4114313040 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 10:03:01.455 T:4114313040 DEBUG: ------ Window Init (Home.xml) ------ 10:03:01.455 T:4114313040 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing.. 10:03:01.455 T:4114313040 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing.. 10:03:01.455 T:4114313040 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing.. 10:03:01.455 T:4114313040 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing.. 10:03:01.456 T:3663721376 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:03:01.456 T:4114313040 DEBUG: CDirectoryProvider[pvr://channels/tv/*?view=lastplayed]: refreshing.. 10:03:01.456 T:4114313040 DEBUG: CDirectoryProvider[pvr://recordings/tv/active?view=flat]: refreshing.. 10:03:01.456 T:4114313040 DEBUG: CDirectoryProvider[pvr://channels/radio/*?view=lastplayed]: refreshing.. 10:03:01.461 T:3663721376 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:03:01.462 T:4026524576 DEBUG: Thread JobWorker start, auto delete: true 10:03:01.462 T:4026524576 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:03:01.474 T:4026524576 DEBUG: Previous line repeats 2 times. 10:03:01.474 T:4026524576 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/tv/*?view=lastplayed) 10:03:01.476 T:3663721376 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:03:01.476 T:3663721376 DEBUG: CPVRDirectory::GetDirectory(pvr://recordings/tv/active?view=flat) 10:03:01.487 T:4026524576 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:03:01.487 T:4026524576 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/radio/*?view=lastplayed) 10:03:01.488 T:4026524576 INFO: easy_aquire - Created session to http://127.0.0.1 10:03:01.489 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/40 10:03:01.491 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/37 10:03:01.493 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/1 10:03:01.495 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/4 10:03:01.498 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/10 10:03:01.501 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/39 10:03:01.503 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/2 10:03:01.505 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/5 10:03:01.507 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/12 10:03:01.509 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/33 10:03:01.511 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/41 10:03:01.512 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/7 10:03:01.515 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/38 10:03:01.517 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/13 10:03:01.519 T:4026524576 DEBUG: GetImageHash - unable to stat url http://127.0.0.1:9981/imagecache/3 10:03:05.255 T:3969913760 DEBUG: PushCecKeypress - received key b duration 0 10:03:05.288 T:4114313040 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 10:03:05.288 T:4114313040 DEBUG: Activating window ID: 10025 10:03:05.324 T:3969913760 DEBUG: PushCecKeypress - received key b duration 69 10:03:05.606 T:4114313040 DEBUG: ------ Window Deinit (Home.xml) ------ 10:03:05.614 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:03:05.619 T:4114313040 DEBUG: Previous line repeats 6 times. 10:03:05.619 T:4114313040 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 10:03:05.620 T:4114313040 DEBUG: CGUIMediaWindow::GetDirectory (library://video/files.xml/) 10:03:05.620 T:4114313040 DEBUG: ParentPath = [library://video/files.xml/] 10:03:05.621 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:03:05.634 T:3911193504 DEBUG: Previous line repeats 3 times. 10:03:05.634 T:3911193504 DEBUG: Thread BackgroundLoader start, auto delete: false 10:03:05.639 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:03:05.644 T:3911193504 DEBUG: Previous line repeats 6 times. 10:03:05.645 T:3911193504 DEBUG: Thread BackgroundLoader 3911193504 terminating 10:03:07.923 T:3969913760 DEBUG: PushCecKeypress - received key a8 duration 0 10:03:07.938 T:4114313040 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 10:03:07.992 T:3969913760 DEBUG: PushCecKeypress - received key a8 duration 69 10:03:08.461 T:3969913760 DEBUG: PushCecKeypress - received key a8 duration 0 10:03:08.501 T:4114313040 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 10:03:08.530 T:3969913760 DEBUG: PushCecKeypress - received key a8 duration 69 10:03:09.105 T:3969913760 DEBUG: PushCecKeypress - received key b duration 0 10:03:09.147 T:4114313040 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 10:03:09.147 T:4114313040 DEBUG: CGUIMediaWindow::GetDirectory (/var/media/NO_TURNING_BACK/) 10:03:09.147 T:4114313040 DEBUG: ParentPath = [sources://video/] 10:03:09.148 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:03:09.174 T:3969913760 DEBUG: PushCecKeypress - received key b duration 69 10:03:09.416 T:3768587168 DEBUG: CPVRTimers - Update - updating timers 10:03:09.648 T:4114313040 DEBUG: ------ Window Init (DialogBusy.xml) ------ 10:03:14.894 T:4114313040 DEBUG: Saving fileitems [/var/media/NO_TURNING_BACK/] 10:03:14.898 T:3911193504 DEBUG: Thread BackgroundLoader start, auto delete: false 10:03:15.056 T:3911193504 DEBUG: Thread BackgroundLoader 3911193504 terminating 10:03:15.143 T:4114313040 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 10:03:16.884 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 10:03:16.900 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:03:16.953 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 10:03:17.416 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 10:03:17.424 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:03:17.485 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 10:03:17.932 T:3969913760 DEBUG: PushCecKeypress - received key b duration 0 10:03:17.956 T:4114313040 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 10:03:17.956 T:4114313040 DEBUG: CGUIMediaWindow::GetDirectory (/var/media/NO_TURNING_BACK/BDMV/) 10:03:17.956 T:4114313040 DEBUG: ParentPath = [/var/media/NO_TURNING_BACK/] 10:03:17.957 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:03:18.001 T:3969913760 DEBUG: PushCecKeypress - received key b duration 69 10:03:18.224 T:4114313040 DEBUG: RunQuery took 0 ms for 1 items query: SELECT files.strFilename, files.playCount, bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files LEFT JOIN bookmark ON files.idFile = bookmark.idFile AND bookmark.type = 1 WHERE files.idPath=22 10:03:18.225 T:3911193504 DEBUG: Thread BackgroundLoader start, auto delete: false 10:03:18.456 T:3911193504 DEBUG: Thread BackgroundLoader 3911193504 terminating 10:03:19.082 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 10:03:19.111 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:03:19.151 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 10:03:19.435 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 10:03:19.463 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:03:19.504 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 10:03:19.687 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 10:03:19.688 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:03:19.756 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 10:03:19.968 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 10:03:19.975 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:03:20.037 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 10:03:20.483 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 10:03:20.515 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:03:20.552 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 10:03:20.885 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 10:03:20.886 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:03:20.954 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 10:03:21.153 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 10:03:21.183 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:03:21.222 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 10:03:21.729 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 10:03:21.759 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:03:21.798 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 10:03:22.270 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 10:03:22.299 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:03:22.339 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 10:03:22.688 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 0 10:03:22.691 T:4114313040 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 10:03:22.757 T:3969913760 DEBUG: PushCecKeypress - received key a7 duration 69 10:03:25.991 T:3969913760 DEBUG: PushCecKeypress - received key b duration 0 10:03:25.995 T:4114313040 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 10:03:26.003 T:4114313040 DEBUG: OnPlayMedia /var/media/NO_TURNING_BACK/BDMV/MovieObject.bdmv 10:03:26.003 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 10:03:26.003 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 10:03:26.003 T:4114313040 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:03:26.010 T:4114313040 DEBUG: Previous line repeats 1 times. 10:03:26.010 T:4114313040 DEBUG: Loading settings for /var/media/NO_TURNING_BACK/BDMV/MovieObject.bdmv 10:03:26.022 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers(/var/media/NO_TURNING_BACK/BDMV/MovieObject.bdmv) 10:03:26.022 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 10:03:26.022 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 10:03:26.022 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 10:03:26.022 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 10:03:26.022 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 10:03:26.022 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 10:03:26.023 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 10:03:26.023 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 10:03:26.023 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 10:03:26.023 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 10:03:26.023 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 10:03:26.023 T:4114313040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 10:03:26.023 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 10:03:26.023 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 10:03:26.023 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 10:03:26.023 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 10:03:26.023 T:4114313040 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 10:03:26.026 T:4114313040 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData 10:03:26.026 T:4114313040 NOTICE: VideoPlayer: Opening: /var/media/NO_TURNING_BACK/BDMV/MovieObject.bdmv 10:03:26.026 T:4114313040 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 10:03:26.026 T:4114313040 DEBUG: LinuxRendererGL: Cleaning up GL resources 10:03:26.027 T:3911193504 DEBUG: Thread VideoPlayer start, auto delete: false 10:03:26.027 T:3911193504 NOTICE: Creating InputStream 10:03:26.027 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:03:26.027 T:3911193504 DEBUG: Previous line repeats 1 times. 10:03:26.027 T:3911193504 DEBUG: SECTION:LoadDLL(libbluray.so.1) 10:03:26.028 T:3911193504 DEBUG: Loading: libbluray.so.1 10:03:26.029 T:3911193504 DEBUG: CDVDInputStreamBluray::Open - opening /var/media/NO_TURNING_BACK 10:03:26.030 T:3911193504 DEBUG: CDVDInputStreamBluray::Logger - bluray.c:1369: libbluray version 0.9.3 10:03:26.030 T:3911193504 DEBUG: CDVDInputStreamBluray::Logger - bluray.c:1390: BLURAY initialized! 10:03:26.030 T:3911193504 DEBUG: CDVDInputStreamBluray - Opening dir /var/media/NO_TURNING_BACK 10:03:26.030 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:03:26.030 T:3911193504 DEBUG: CDVDInputStreamBluray - Closed dir (0xf4007240) 10:03:26.032 T:3911193504 DEBUG: CDVDInputStreamBluray::Logger - aacs.c:78: AACS/Unit_Key_RO.inf found. Disc seems to be AACS protected. 10:03:26.032 T:3911193504 DEBUG: CDVDInputStreamBluray - Error opening file! (0xf4009f20) 10:03:26.032 T:3911193504 DEBUG: CDVDInputStreamBluray::Logger - bdplus.c:86: BDSVM/00000.svm not found. No BD+ protection. 10:03:26.033 T:3911193504 DEBUG: CDVDInputStreamBluray::Logger - aacs.c:104: Using libaacs for AACS 10:03:26.033 T:3911193504 DEBUG: CDVDInputStreamBluray::Logger - aacs.c:128: Loading aacs library (0xf4001d10) 10:03:26.033 T:3911193504 DEBUG: CDVDInputStreamBluray::Logger - aacs.c:139: Loaded libaacs (0xf4001d10) 10:03:26.033 T:3911193504 DEBUG: CDVDInputStreamBluray::Logger - aacs.c:142: Registering libaacs filesystem handler 0x48459c (0xf4001d10) 10:03:26.060 T:3969913760 DEBUG: PushCecKeypress - received key b duration 69 10:03:26.527 T:4114313040 DEBUG: ------ Window Init (DialogBusy.xml) ------ 10:03:27.298 T:3911193504 DEBUG: CDVDInputStreamBluray::Logger - aacs.c:110: No usable AACS libraries found! 10:03:27.299 T:3911193504 DEBUG: CDVDInputStreamBluray::Logger - dec.c:197: aacs_open() failed: -3! 10:03:27.433 T:3911193504 DEBUG: CDVDInputStreamBluray - Opening dir /var/media/NO_TURNING_BACK/BDMV/META/DL 10:03:27.433 T:3911193504 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:03:27.507 T:3911193504 DEBUG: CDVDInputStreamBluray - Closed dir (0xf4004808) 10:03:27.578 T:3911193504 DEBUG: CDVDInputStreamBluray::Open - First Play supported: 1 10:03:27.578 T:3911193504 DEBUG: CDVDInputStreamBluray::Open - Top menu supported : 1 10:03:27.578 T:3911193504 DEBUG: CDVDInputStreamBluray::Open - HDMV titles : 404 10:03:27.578 T:3911193504 DEBUG: CDVDInputStreamBluray::Open - BD-J titles : 0 10:03:27.578 T:3911193504 DEBUG: CDVDInputStreamBluray::Open - UNSUPPORTED titles : 0 10:03:27.578 T:3911193504 DEBUG: CDVDInputStreamBluray::Open - AACS detected : 1 10:03:27.578 T:3911193504 DEBUG: CDVDInputStreamBluray::Open - libaacs detected : 1 10:03:27.578 T:3911193504 DEBUG: CDVDInputStreamBluray::Open - AACS handled : 0 10:03:27.578 T:3911193504 DEBUG: CDVDInputStreamBluray::Open - BD+ detected : 0 10:03:27.578 T:3911193504 DEBUG: CDVDInputStreamBluray::Open - libbdplus detected : 0 10:03:27.578 T:3911193504 DEBUG: CDVDInputStreamBluray::Open - BD+ handled : 0 10:03:27.578 T:3911193504 ERROR: CDVDInputStreamBluray::Open - Media stream scrambled/encrypted with AACS 10:03:27.579 T:3911193504 ERROR: CVideoPlayer::OpenInputStream - error opening [/var/media/NO_TURNING_BACK/BDMV/MovieObject.bdmv] 10:03:27.579 T:3911193504 NOTICE: CVideoPlayer::OnExit() 10:03:27.579 T:3911193504 DEBUG: CDVDInputStreamBluray::Logger - bluray.c:1507: BLURAY destroyed! 10:03:27.579 T:3911193504 DEBUG: OnPlayBackStopped: play state was 1, starting 1 10:03:27.579 T:3911193504 DEBUG: Thread VideoPlayer 3911193504 terminating 10:03:27.582 T:4114313040 DEBUG: OnPlayBackStopped: play state was 3, starting 0 10:03:27.582 T:4114297760 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 10:03:27.582 T:4114297760 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 10:03:27.582 T:4114313040 ERROR: Playlist Player: skipping unplayable item: 0, path [/var/media/NO_TURNING_BACK/BDMV/MovieObject.bdmv] 10:03:27.582 T:4114313040 DEBUG: Playlist Player: no more playable items... aborting playback 10:03:27.583 T:3911193504 DEBUG: Thread BackgroundLoader start, auto delete: false 10:03:27.598 T:4114313040 NOTICE: CVideoPlayer::CloseFile() 10:03:27.598 T:4114313040 NOTICE: VideoPlayer: waiting for threads to exit 10:03:27.598 T:4114313040 NOTICE: VideoPlayer: finished waiting 10:03:27.599 T:4114313040 DEBUG: DeleteRenderer - deleting renderer 10:03:27.599 T:4114313040 DEBUG: LinuxRendererGL: Cleaning up GL resources 10:03:27.599 T:4114313040 NOTICE: CVideoPlayer::CloseFile() 10:03:27.599 T:4114313040 NOTICE: VideoPlayer: waiting for threads to exit 10:03:27.599 T:4114313040 NOTICE: VideoPlayer: finished waiting 10:03:27.601 T:4114313040 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 10:03:27.610 T:3911193504 DEBUG: Thread BackgroundLoader 3911193504 terminating 10:03:27.847 T:4114313040 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 10:03:28.064 T:4026524576 DEBUG: DoWork - Saving file state for video item /var/media/NO_TURNING_BACK/BDMV/MovieObject.bdmv 10:03:28.129 T:4026524576 DEBUG: CSettingsManager: requested setting (filelists.browsearchives) was not found. 10:03:31.703 T:4114313040 DEBUG: Previous line repeats 3 times. 10:03:31.703 T:4114313040 INFO: CheckIdle - Closing session to http://127.0.0.1 (easy=0xda668c20, multi=(nil))