=============================================================
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))