19:11:21.275 T:140277129455680 NOTICE: special://profile/ is mapped to: special://masterprofile/ 19:11:21.275 T:140277129455680 NOTICE: ----------------------------------------------------------------------- 19:11:21.275 T:140277129455680 NOTICE: Starting Kodi (17.1 Git:20170320-nogitfound). Platform: Linux x86 64-bit 19:11:21.275 T:140277129455680 NOTICE: Using Release Kodi x64 build 19:11:21.275 T:140277129455680 NOTICE: Kodi compiled Mar 20 2017 by GCC 4.8.4 for Linux x86 64-bit version 3.13.11 (199947) 19:11:21.275 T:140277129455680 NOTICE: Running on Ubuntu 14.04.2 LTS, kernel: Linux x86 64-bit version 4.4.0-67-generic 19:11:21.276 T:140277129455680 NOTICE: FFmpeg version/source: ffmpeg-3.1-kodi 19:11:21.276 T:140277129455680 NOTICE: Host CPU: Intel(R) Atom(TM) CPU 330 @ 1.60GHz, 4 cores available 19:11:21.276 T:140277129455680 NOTICE: special://xbmc/ is mapped to: /usr/share/kodi 19:11:21.276 T:140277129455680 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/x86_64-linux-gnu/kodi 19:11:21.276 T:140277129455680 NOTICE: special://xbmcbinaddons/ is mapped to: /usr/lib/x86_64-linux-gnu/kodi/addons 19:11:21.276 T:140277129455680 NOTICE: special://masterprofile/ is mapped to: /home/ondrej/.kodi/userdata 19:11:21.276 T:140277129455680 NOTICE: special://envhome/ is mapped to: /home/ondrej 19:11:21.277 T:140277129455680 NOTICE: special://home/ is mapped to: /home/ondrej/.kodi 19:11:21.277 T:140277129455680 NOTICE: special://temp/ is mapped to: /home/ondrej/.kodi/temp 19:11:21.277 T:140277129455680 NOTICE: special://logpath/ is mapped to: /home/ondrej/.kodi/temp 19:11:21.277 T:140277129455680 NOTICE: The executable running is: /usr/lib/x86_64-linux-gnu/kodi/kodi.bin 19:11:21.277 T:140277129455680 NOTICE: Local hostname: atom 19:11:21.277 T:140277129455680 NOTICE: Log File is located: /home/ondrej/.kodi/temp//kodi.log 19:11:21.277 T:140277129455680 NOTICE: ----------------------------------------------------------------------- 19:11:21.337 T:140277129455680 NOTICE: load settings... 19:11:21.406 T:140277129455680 NOTICE: Found 1 Lists of Devices 19:11:21.406 T:140277129455680 NOTICE: Enumerated PULSE devices: 19:11:21.406 T:140277129455680 NOTICE: Device 1 19:11:21.406 T:140277129455680 NOTICE: m_deviceName : Default 19:11:21.406 T:140277129455680 NOTICE: m_displayName : Default 19:11:21.406 T:140277129455680 NOTICE: m_displayNameExtra: Default Output Device (PULSEAUDIO) 19:11:21.406 T:140277129455680 NOTICE: m_deviceType : AE_DEVTYPE_PCM 19:11:21.406 T:140277129455680 NOTICE: m_channels : FL,FR 19:11:21.406 T:140277129455680 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000 19:11:21.406 T:140277129455680 NOTICE: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT 19:11:21.406 T:140277129455680 NOTICE: m_streamTypes : No passthrough capabilities 19:11:21.406 T:140277129455680 NOTICE: Device 2 19:11:21.407 T:140277129455680 NOTICE: m_deviceName : alsa_output.pci-0000_00_08.0.analog-stereo 19:11:21.407 T:140277129455680 NOTICE: m_displayName : Vstavané audio Analógový stereo 19:11:21.407 T:140277129455680 NOTICE: m_displayNameExtra: Analógový výstup (PULSEAUDIO) 19:11:21.407 T:140277129455680 NOTICE: m_deviceType : AE_DEVTYPE_PCM 19:11:21.407 T:140277129455680 NOTICE: m_channels : FL,FR 19:11:21.407 T:140277129455680 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000 19:11:21.407 T:140277129455680 NOTICE: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT 19:11:21.407 T:140277129455680 NOTICE: m_streamTypes : No passthrough capabilities 19:11:21.423 T:140277129455680 NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml) 19:11:21.423 T:140277129455680 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml) 19:11:21.423 T:140277129455680 NOTICE: Default Video Player: VideoPlayer 19:11:21.423 T:140277129455680 NOTICE: Default Audio Player: paplayer 19:11:21.424 T:140277129455680 NOTICE: Enabled debug logging due to GUI setting (2) 19:11:21.424 T:140277129455680 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM" 19:11:21.424 T:140277129455680 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 19:11:21.425 T:140277129455680 DEBUG: CPlayerCoreConfig::: created player VideoPlayer 19:11:21.425 T:140277129455680 DEBUG: CPlayerCoreConfig::: created player PAPlayer 19:11:21.425 T:140277129455680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules 19:11:21.425 T:140277129455680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mms/udp 19:11:21.425 T:140277129455680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout 19:11:21.425 T:140277129455680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp 19:11:21.425 T:140277129455680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp 19:11:21.425 T:140277129455680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams 19:11:21.425 T:140277129455680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp 19:11:21.425 T:140277129455680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2 19:11:21.425 T:140277129455680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd 19:11:21.425 T:140277129455680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage 19:11:21.426 T:140277129455680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf 19:11:21.426 T:140277129455680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv 19:11:21.426 T:140277129455680 DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio 19:11:21.426 T:140277129455680 NOTICE: Loaded playercorefactory configuration 19:11:21.426 T:140277129455680 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml. 19:11:21.426 T:140277129455680 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping. 19:11:21.440 T:140277129455680 INFO: creating subdirectories 19:11:21.440 T:140277129455680 INFO: userdata folder: special://masterprofile/ 19:11:21.440 T:140277129455680 INFO: recording folder: 19:11:21.440 T:140277129455680 INFO: screenshots folder: 19:11:21.454 T:140277129455680 NOTICE: Running database version Addons27 19:11:21.454 T:140277129455680 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-x86_64-linux.so) 19:11:21.454 T:140277129455680 DEBUG: Loading: /usr/lib/x86_64-linux-gnu/kodi/system/libcpluff-x86_64-linux.so 19:11:21.465 T:140277129455680 INFO: ADDON: cpluff: 'Could not read plug-in directory /home/ondrej/.kodi/addons: No such file or directory' 19:11:21.515 T:140277129455680 INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/lib/x86_64-linux-gnu/kodi/addons: No such file or directory' 19:11:21.516 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.' 19:11:21.516 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been installed.' 19:11:21.516 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in resource.images.weathericons.default has been installed.' 19:11:21.516 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.audioencoder has been installed.' 19:11:21.516 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.' 19:11:21.516 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in kodi.audiodecoder has been installed.' 19:11:21.516 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.' 19:11:21.516 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in kodi.guilib has been installed.' 19:11:21.516 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.' 19:11:21.516 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in kodi.peripheral has been installed.' 19:11:21.516 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in kodi.adsp has been installed.' 19:11:21.516 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.wma has been installed.' 19:11:21.516 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in game.controller.default has been installed.' 19:11:21.516 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.' 19:11:21.516 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in kodi.game has been installed.' 19:11:21.516 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.' 19:11:21.516 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in service.xbmc.versioncheck has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in kodi.resource has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in skin.estouchy has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in kodi.inputstream has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.aac has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in resource.language.en_gb has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.webinterface has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.kodi has been installed.' 19:11:21.517 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.' 19:11:21.518 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been installed.' 19:11:21.518 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been installed.' 19:11:21.518 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.' 19:11:21.518 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.' 19:11:21.518 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.' 19:11:21.518 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been installed.' 19:11:21.518 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in skin.estuary has been installed.' 19:11:21.518 T:140277129455680 DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.' 19:11:21.537 T:140276517562112 DEBUG: Thread JobWorker start, auto delete: true 19:11:21.551 T:140277129455680 NOTICE: ADDONS: Using repository repository.xbmc.org 19:11:21.559 T:140276584675072 DEBUG: Thread ActiveAE start, auto delete: false 19:11:21.560 T:140276576282368 DEBUG: Thread AESink start, auto delete: false 19:11:21.562 T:140276576282368 INFO: CActiveAESink::OpenSink - initialize sink 19:11:21.562 T:140276576282368 DEBUG: CActiveAESink::OpenSink - trying to open device PULSE:Default 19:11:21.565 T:140276576282368 DEBUG: PulseAudio: Context authorizing 19:11:21.566 T:140276576282368 DEBUG: PulseAudio: Context setting name 19:11:21.567 T:140276576282368 DEBUG: PulseAudio: Context ready 19:11:21.593 T:140276576282368 DEBUG: PulseAudio: Stream ready 19:11:21.593 T:140276576282368 NOTICE: PulseAudio: Opened device Default in pcm mode with Buffersize 150 ms 19:11:21.602 T:140276576282368 DEBUG: CActiveAESink::OpenSink - PULSE Initialized: 19:11:21.602 T:140276576282368 DEBUG: Output Device : Default 19:11:21.602 T:140276576282368 DEBUG: Sample Rate : 44100 19:11:21.602 T:140276576282368 DEBUG: Sample Format : AE_FMT_FLOAT 19:11:21.602 T:140276576282368 DEBUG: Channel Count : 2 19:11:21.602 T:140276576282368 DEBUG: Channel Layout: FL,FR 19:11:21.602 T:140276576282368 DEBUG: Frames : 2205 19:11:21.602 T:140276576282368 DEBUG: Frame Size : 8 19:11:21.620 T:140276559496960 DEBUG: Thread RemoteControl start, auto delete: false 19:11:21.620 T:140276559496960 INFO: LIRC Process: using: /dev/lircd 19:11:21.621 T:140276559496960 INFO: LIRC Connect: connect failed: No such file or directory 19:11:21.621 T:140276559496960 INFO: CRemoteControl::Process - failed to connect to LIRC, will keep retrying every 5 seconds 19:11:21.628 T:140277129455680 INFO: CKeyboardLayoutManager: loading keyboard layouts from special://xbmc/system/keyboardlayouts... 19:11:21.636 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BasePY" successfully loaded 19:11:21.637 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BaiduPY" successfully loaded 19:11:21.642 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian ЯВЕРТЪ" successfully loaded 19:11:21.644 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian АБВ" successfully loaded 19:11:21.648 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Portuguese (Portugal) QWERTY" successfully loaded 19:11:21.650 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian AZERTY" successfully loaded 19:11:21.651 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian QWERTY" successfully loaded 19:11:21.654 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Turkish QWERTY" successfully loaded 19:11:21.656 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Romanian QWERTY" successfully loaded 19:11:21.658 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Korean ㄱㄴㄷ" successfully loaded 19:11:21.662 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Croatian QWERTY" successfully loaded 19:11:21.666 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "French AZERTY" successfully loaded 19:11:21.670 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "English QWERTY" successfully loaded 19:11:21.670 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "English AZERTY" successfully loaded 19:11:21.671 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "English ABC" successfully loaded 19:11:21.672 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Spanish QWERTY" successfully loaded 19:11:21.674 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian ЙЦУКЕН" successfully loaded 19:11:21.674 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian АБВ" successfully loaded 19:11:21.676 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian ЙЦУКЕН" successfully loaded 19:11:21.676 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian АБВ" successfully loaded 19:11:21.678 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Czech QWERTZ" successfully loaded 19:11:21.679 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Italian QWERTY" successfully loaded 19:11:21.681 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTZ" successfully loaded 19:11:21.682 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTY" successfully loaded 19:11:21.682 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak ABC" successfully loaded 19:11:21.684 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Danish QWERTY" successfully loaded 19:11:21.686 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Arabic QWERTY" successfully loaded 19:11:21.687 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "German QWERTZ" successfully loaded 19:11:21.688 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "German ABC" successfully loaded 19:11:21.689 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Norwegian QWERTY" successfully loaded 19:11:21.691 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Greek QWERTY" successfully loaded 19:11:21.692 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Silesian QWERTY" successfully loaded 19:11:21.694 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Polish QWERTY" successfully loaded 19:11:21.695 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Hungarian QWERTZ" successfully loaded 19:11:21.697 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew QWERTY" successfully loaded 19:11:21.698 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew ABC" successfully loaded 19:11:21.699 T:140277129455680 DEBUG: CKeyboardLayoutManager: keyboard layout "Swedish QWERTY" successfully loaded 19:11:22.055 T:140277129455680 DEBUG: Selected UDisks as storage provider 19:11:22.057 T:140277129455680 DEBUG: UDisks: DaemonVersion 1 19:11:22.057 T:140277129455680 DEBUG: UDisks: Querying available devices 19:11:22.084 T:140277129455680 INFO: UDisks: Added /media/ondrej/System 19:11:22.093 T:140277129455680 INFO: UDisks: Added /media/ondrej/Storage 19:11:22.144 T:140277129455680 INFO: UDisks: Added /data 19:11:22.178 T:140277129455680 INFO: UDisks: Added /media/ondrej/IKARIE_XB_1 19:11:22.266 T:140277129455680 INFO: Available videomodes (xrandr): 19:11:22.266 T:140277129455680 INFO: Output 'DVI-D-0' has 7 modes 19:11:22.266 T:140277129455680 INFO: ID:0x24e Name:1280x1024 Refresh:60.019741 Width:1280 Height:1024 19:11:22.266 T:140277129455680 INFO: Pixel Ratio: 1.001481 19:11:22.266 T:140277129455680 INFO: ID:0x24f Name:1024x768 Refresh:75.028580 Width:1024 Height:768 19:11:22.266 T:140277129455680 INFO: Pixel Ratio: 0.938889 19:11:22.266 T:140277129455680 INFO: ID:0x250 Name:1024x768 Refresh:60.003841 Width:1024 Height:768 19:11:22.266 T:140277129455680 INFO: Pixel Ratio: 0.938889 19:11:22.266 T:140277129455680 INFO: ID:0x251 Name:800x600 Refresh:75.000000 Width:800 Height:600 19:11:22.266 T:140277129455680 INFO: Pixel Ratio: 0.938889 19:11:22.266 T:140277129455680 INFO: ID:0x252 Name:800x600 Refresh:60.316540 Width:800 Height:600 19:11:22.267 T:140277129455680 INFO: Pixel Ratio: 0.938889 19:11:22.267 T:140277129455680 INFO: ID:0x253 Name:640x480 Refresh:75.000000 Width:640 Height:480 19:11:22.267 T:140277129455680 INFO: Pixel Ratio: 0.938889 19:11:22.267 T:140277129455680 INFO: ID:0x254 Name:640x480 Refresh:59.940479 Width:640 Height:480 19:11:22.267 T:140277129455680 INFO: Pixel Ratio: 0.938889 19:11:22.267 T:140277129455680 NOTICE: Checking resolution 16 19:11:22.350 T:140277129455680 DEBUG: Window Manager Name: Metacity (Marco) 19:11:22.892 T:140277129455680 ERROR: Failed to determine egl config for visual info 19:11:22.892 T:140277129455680 WARNING: Visual 0x28 of the window is not suitable, looking for another one... 19:11:22.892 T:140277129455680 NOTICE: Using visual 0x21 19:11:22.933 T:140277129455680 NOTICE: Using visual 0x28 19:11:23.064 T:140277129455680 INFO: GL: Maximum texture width: 8192 19:11:23.065 T:140277129455680 DEBUG: GLX_EXTENSIONS: GLX_EXT_visual_info GLX_EXT_visual_rating GLX_SGIX_fbconfig GLX_SGIX_pbuffer GLX_SGI_video_sync GLX_SGI_swap_control GLX_EXT_swap_control GLX_EXT_swap_control_tear GLX_EXT_texture_from_pixmap GLX_EXT_buffer_age GLX_ARB_create_context GLX_ARB_create_context_profile GLX_EXT_create_context_es_profile GLX_EXT_create_context_es2_profile GLX_ARB_create_context_robustness GLX_NV_delay_before_swap GLX_EXT_stereo_tree GLX_ARB_multisample GLX_NV_float_buffer GLX_ARB_fbconfig_float GLX_EXT_framebuffer_sRGB GLX_NV_multisample_coverage GLX_ARB_get_proc_address 19:11:23.065 T:140277129455680 NOTICE: GL_VENDOR = NVIDIA Corporation 19:11:23.065 T:140277129455680 NOTICE: GL_RENDERER = ION/integrated/SSE2 19:11:23.065 T:140277129455680 NOTICE: GL_VERSION = 3.3.0 NVIDIA 340.102 19:11:23.065 T:140277129455680 NOTICE: GL_SHADING_LANGUAGE_VERSION = 3.30 NVIDIA via Cg compiler 19:11:23.066 T:140277129455680 NOTICE: GL_GPU_MEMORY_INFO_TOTAL_AVAILABLE_MEMORY_NVX = 262144 19:11:23.066 T:140277129455680 NOTICE: GL_GPU_MEMORY_INFO_DEDICATED_VIDMEM_NVX = 262144 19:11:23.066 T:140277129455680 NOTICE: GL_EXTENSIONS = GL_ARB_arrays_of_arrays GL_ARB_base_instance GL_ARB_blend_func_extended GL_ARB_clear_buffer_object GL_ARB_color_buffer_float GL_ARB_compatibility GL_ARB_compressed_texture_pixel_storage GL_ARB_conservative_depth GL_ARB_copy_buffer GL_ARB_copy_image GL_ARB_debug_output GL_ARB_depth_buffer_float GL_ARB_depth_clamp GL_ARB_depth_texture GL_ARB_draw_buffers GL_ARB_draw_elements_base_vertex GL_ARB_draw_instanced GL_ARB_enhanced_layouts GL_ARB_ES2_compatibility GL_ARB_ES3_compatibility GL_ARB_explicit_attrib_location GL_ARB_explicit_uniform_location GL_ARB_fragment_coord_conventions GL_ARB_fragment_layer_viewport GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_framebuffer_no_attachments GL_ARB_framebuffer_object GL_ARB_framebuffer_sRGB GL_ARB_geometry_shader4 GL_ARB_get_program_binary GL_ARB_half_float_pixel GL_ARB_half_float_vertex GL_ARB_imaging GL_ARB_instanced_arrays GL_ARB_internalformat_query GL_ARB_internalformat_query2 GL_ARB_invalidate_subdata GL_ARB_map_buffer_alignment GL_ARB_map_buffer_range GL_ARB_multi_bind GL_ARB_multisample GL_ARB_multitexture GL_ARB_occlusion_query GL_ARB_occlusion_query2 GL_ARB_pixel_buffer_object GL_ARB_point_parameters GL_ARB_point_sprite GL_ARB_program_interface_query GL_ARB_provoking_vertex GL_ARB_robust_buffer_access_behavior GL_ARB_robustness GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_separate_shader_objects GL_ARB_shader_bit_encoding GL_ARB_shader_objects GL_ARB_shader_texture_lod GL_ARB_shading_language_100 GL_ARB_shading_language_420pack GL_ARB_shading_language_include GL_ARB_shading_language_packing GL_ARB_shadow GL_ARB_stencil_texturing GL_ARB_sync GL_ARB_texture_border_clamp GL_ARB_texture_buffer_object GL_ARB_texture_buffer_range GL_ARB_texture_compression GL_ARB_texture_compression_rgtc GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_float GL_ARB_texture_mirror_clamp_to_edge GL_ARB_texture_mirrored_repeat GL_ARB_texture_multisample GL_ARB_texture_non_power_of_two GL_ARB_texture_query_levels GL_ARB_texture_rectangle GL_ARB_texture_rg GL_ARB_texture_rgb10_a2ui GL_ARB_texture_stencil8 GL_ARB_texture_storage GL_ARB_texture_storage_multisample GL_ARB_texture_swizzle GL_ARB_texture_view GL_ARB_timer_query GL_ARB_transform_feedback2 GL_ARB_transform_feedback_instanced GL_ARB_transpose_matrix GL_ARB_uniform_buffer_object GL_ARB_vertex_array_bgra GL_ARB_vertex_array_object GL_ARB_vertex_attrib_binding GL_ARB_vertex_buffer_object GL_ARB_vertex_program GL_ARB_vertex_shader GL_ARB_vertex_type_10f_11f_11f_rev GL_ARB_vertex_type_2_10_10_10_rev GL_ARB_viewport_array GL_ARB_window_pos GL_ATI_draw_buffers GL_ATI_texture_float GL_ATI_texture_mirror_once GL_S3_s3tc GL_EXT_texture_env_add GL_EXT_abgr GL_EXT_bgra GL_EXT_bindable_uniform GL_EXT_blend_color GL_EXT_blend_equation_separate GL_EXT_blend_func_separate GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_compiled_vertex_array GL_EXT_Cg_shader GL_EXT_depth_bounds_test GL_EXT_direct_state_access GL_EXT_draw_buffers2 GL_EXT_draw_instanced GL_EXT_draw_range_elements GL_EXT_fog_coord GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXTX_framebuffer_mixed_formats GL_EXT_framebuffer_multisample_blit_scaled GL_EXT_framebuffer_object GL_EXT_framebuffer_sRGB GL_EXT_geometry_shader4 GL_EXT_gpu_program_parameters GL_EXT_gpu_shader4 GL_EXT_multi_draw_arrays GL_EXT_packed_depth_stencil GL_EXT_packed_float GL_EXT_packed_pixels GL_EXT_pixel_buffer_object GL_EXT_point_parameters GL_EXT_provoking_vertex GL_EXT_rescale_normal GL_EXT_secondary_color GL_EXT_separate_shader_objects GL_EXT_separate_specular_color GL_EXT_shader_integer_mix GL_EXT_shadow_funcs GL_EXT_stencil_two_side GL_EXT_stencil_wrap GL_EXT_texture3D GL_EXT_texture_array GL_EXT_texture_buffer_object GL_EXT_texture_compression_dxt1 GL_EXT_texture_compression_latc GL_EXT_texture_compression_rgtc GL_EXT_texture_compression_s3tc GL_EXT_texture_cube_map GL_EXT_texture_edge_clamp GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_EXT_texture_filter_anisotropic GL_EXT_texture_integer GL_EXT_texture_lod GL_EXT_texture_lod_bias GL_EXT_texture_mirror_clamp GL_EXT_texture_object GL_EXT_texture_shared_exponent GL_EXT_texture_sRGB GL_EXT_texture_sRGB_decode GL_EXT_texture_storage GL_EXT_texture_swizzle GL_EXT_timer_query GL_EXT_transform_feedback2 GL_EXT_vertex_array GL_EXT_vertex_array_bgra GL_EXT_x11_sync_object GL_EXT_import_sync_object GL_IBM_rasterpos_clip GL_IBM_texture_mirrored_repeat GL_KHR_debug GL_KTX_buffer_region GL_NV_blend_square GL_NV_conditional_render GL_NV_copy_depth_to_color GL_NV_copy_image GL_NV_depth_buffer_float GL_NV_depth_clamp GL_NV_ES1_1_compatibility GL_NV_explicit_multisample GL_NV_fence GL_NV_float_buffer GL_NV_fog_distance GL_NV_fragment_program GL_NV_fragment_program_option GL_NV_fragment_program2 GL_NV_framebuffer_multisample_coverage GL_NV_geometry_shader4 GL_NV_gpu_program4 GL_NV_half_float GL_NV_light_max_exponent GL_NV_multisample_coverage GL_NV_multisample_filter_hint GL_NV_occlusion_query GL_NV_packed_depth_stencil GL_NV_parameter_buffer_object GL_NV_parameter_buffer_object2 GL_NV_path_rendering GL_NV_pixel_data_range GL_NV_point_sprite GL_NV_primitive_restart GL_NV_register_combiners GL_NV_register_combiners2 GL_NV_shader_buffer_load GL_NV_texgen_reflection GL_NV_texture_barrier GL_NV_texture_compression_vtc GL_NV_texture_env_combine4 GL_NV_texture_expand_normal GL_NV_texture_multisample GL_NV_texture_rectangle GL_NV_texture_shader GL_NV_texture_shader2 GL_NV_texture_shader3 GL_NV_transform_feedback GL_NV_transform_feedback2 GL_NV_vdpau_interop GL_NV_vertex_array_range GL_NV_vertex_array_range2 GL_NV_vertex_buffer_unified_memory GL_NV_vertex_program GL_NV_vertex_program1_1 GL_NV_vertex_program2 GL_NV_vertex_program2_option GL_NV_vertex_program3 GL_NVX_conditional_render GL_NVX_gpu_memory_info GL_SGIS_generate_mipmap GL_SGIS_texture_lod GL_SGIX_depth_texture GL_SGIX_shadow GL_SUN_slice_accum 19:11:23.067 T:140277129455680 INFO: GL: Maximum texture width: 8192 19:11:23.308 T:140277129455680 INFO: GL: Enabling VSYNC 19:11:23.315 T:140277129455680 INFO: load keymapping 19:11:23.321 T:140277129455680 INFO: Loading special://xbmc/system/keymaps/appcommand.xml 19:11:23.322 T:140277129455680 INFO: Loading special://xbmc/system/keymaps/customcontroller.AppleRemote.xml 19:11:23.325 T:140277129455680 INFO: Loading special://xbmc/system/keymaps/customcontroller.Harmony.xml 19:11:23.330 T:140277129455680 INFO: Loading special://xbmc/system/keymaps/gamepad.xml 19:11:23.336 T:140277129455680 INFO: Loading special://xbmc/system/keymaps/joystick.xml 19:11:23.342 T:140277129455680 INFO: Loading special://xbmc/system/keymaps/keyboard.xml 19:11:23.362 T:140277129455680 INFO: Loading special://xbmc/system/keymaps/mouse.xml 19:11:23.364 T:140277129455680 INFO: Loading special://xbmc/system/keymaps/remote.xml 19:11:23.377 T:140277129455680 INFO: Loading special://xbmc/system/keymaps/touchscreen.xml 19:11:23.382 T:140277129455680 INFO: Loading special://xbmc/system/Lircmap.xml 19:11:23.387 T:140277129455680 INFO: * Adding remote mapping for device 'mceusb' 19:11:23.387 T:140277129455680 INFO: * Adding remote mapping for device 'XboxDVDDongle' 19:11:23.387 T:140277129455680 INFO: * Adding remote mapping for device 'Microsoft_Xbox' 19:11:23.387 T:140277129455680 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote' 19:11:23.387 T:140277129455680 INFO: * Adding remote mapping for device 'anysee' 19:11:23.388 T:140277129455680 INFO: * Adding remote mapping for device 'iMON-PAD' 19:11:23.388 T:140277129455680 INFO: * Adding remote mapping for device 'Antec_Veris_RM200' 19:11:23.388 T:140277129455680 INFO: * Adding remote mapping for device 'MCE_via_iMON' 19:11:23.388 T:140277129455680 INFO: * Adding remote mapping for device 'TwinHanRemote' 19:11:23.388 T:140277129455680 INFO: * Adding remote mapping for device 'linux-input-layer' 19:11:23.388 T:140277129455680 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote' 19:11:23.388 T:140277129455680 INFO: * Adding remote mapping for device 'mediacenter' 19:11:23.388 T:140277129455680 INFO: * Adding remote mapping for device 'devinput' 19:11:23.389 T:140277129455680 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping 19:11:23.389 T:140277129455680 INFO: GUI format 1280x1024, Display 1280x1024@ 60.02 - Full Screen 19:11:23.389 T:140277129455680 DEBUG: guilib: Fill viewport on change for solving rendering passes 19:11:23.390 T:140277129455680 INFO: CLangInfo: loading resource.language.en_gb language information... 19:11:23.393 T:140277129455680 DEBUG: trying to set locale to en_US.UTF-8 19:11:23.394 T:140277129455680 INFO: global locale set to en_US.UTF-8 19:11:23.394 T:140277129455680 INFO: CLangInfo: loading resource.language.en_gb language strings... 19:11:23.431 T:140277129455680 DEBUG: LocalizeStrings: loaded 3773 strings from file resource://resource.language.en_gb/strings.po 19:11:23.444 T:140277129455680 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.xbmc.builtin.aac/resources/language/English/strings.po 19:11:23.446 T:140277129455680 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.xbmc.builtin.wma/resources/language/English/strings.po 19:11:23.448 T:140277129455680 DEBUG: LocalizeStrings: loaded 18 strings from file /usr/share/kodi/addons/game.controller.default/resources/language/resource.language.en_gb/strings.po 19:11:23.450 T:140277129455680 DEBUG: LocalizeStrings: loaded 13 strings from file /usr/share/kodi/addons/metadata.album.universal/resources/language/English/strings.po 19:11:23.452 T:140277129455680 DEBUG: LocalizeStrings: loaded 26 strings from file /usr/share/kodi/addons/metadata.artists.universal/resources/language/English/strings.po 19:11:23.461 T:140277129455680 DEBUG: LocalizeStrings: loaded 7 strings from file /home/ondrej/.kodi/addons/metadata.themoviedb.org/resources/language/English/strings.po 19:11:23.467 T:140277129455680 DEBUG: LocalizeStrings: loaded 7 strings from file /usr/share/kodi/addons/metadata.tvdb.com/resources/language/English/strings.po 19:11:23.478 T:140277129455680 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/screensaver.xbmc.builtin.dim/resources/language/resource.language.en_gb/strings.po 19:11:23.483 T:140277129455680 DEBUG: LocalizeStrings: loaded 21 strings from file /home/ondrej/.kodi/addons/service.xbmc.versioncheck/resources/language/English/strings.po 19:11:23.494 T:140277129455680 DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid" 19:11:23.494 T:140277129455680 DEBUG: LoadMappings - loaded node "CEC Adapter" 19:11:23.494 T:140277129455680 DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter" 19:11:23.495 T:140277129455680 DEBUG: LoadMappings - loaded node "iMON HID device" 19:11:23.495 T:140277129455680 DEBUG: LoadMappings - loaded node "WETEK Play remote" 19:11:23.495 T:140277129455680 DEBUG: LoadMappings - loaded node "" 19:11:23.496 T:140277129455680 DEBUG: CPeripheralBusUSB - initialised udev monitor 19:11:23.497 T:140277129455680 DEBUG: SECTION:LoadDLL(libcec.so.4) 19:11:23.499 T:140277129455680 DEBUG: Loading: libcec.so.4 19:11:23.527 T:140276459857664 DEBUG: Thread PeripBusUSBUdev start, auto delete: false 19:11:23.530 T:140276468250368 DEBUG: Thread PeripBusCEC start, auto delete: false 19:11:23.538 T:140276476643072 DEBUG: Thread PeripBusAddon start, auto delete: false 19:11:23.538 T:140277129455680 DEBUG: SECTION:LoadDLL(libcurl.so.4) 19:11:23.541 T:140277129455680 DEBUG: Loading: libcurl.so.4 19:11:23.542 T:140276152104704 DEBUG: Thread PeripEventScanner start, auto delete: false 19:11:23.628 T:140276517562112 NOTICE: Running database version Addons27 19:11:23.628 T:140276517562112 DEBUG: Initialize, updating databases... 19:11:23.631 T:140276517562112 NOTICE: Running database version ViewModes6 19:11:23.635 T:140276517562112 NOTICE: Running database version Textures13 19:11:23.647 T:140276517562112 NOTICE: Running database version MyMusic60 19:11:23.665 T:140276517562112 NOTICE: Running database version MyVideos107 19:11:23.668 T:140276517562112 NOTICE: Running database version TV29 19:11:23.672 T:140276517562112 NOTICE: Running database version Epg11 19:11:23.675 T:140276517562112 DEBUG: Initialize, updating databases... DONE 19:11:23.677 T:140277129455680 NOTICE: start dvd mediatype detection 19:11:23.678 T:140277129455680 DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY 19:11:23.689 T:140277127931648 DEBUG: Thread DetectDVDMedia start, auto delete: false 19:11:23.689 T:140277127931648 DEBUG: Compiled with libcdio Version 0.83 19:11:23.735 T:140276668229376 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 19:11:23.735 T:140276668229376 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 19:11:23.753 T:140277129455680 INFO: Unloading old skin ... 19:11:23.757 T:140277129455680 INFO: load skin from: /usr/share/kodi/addons/skin.estuary (version: 1.9.12) 19:11:23.757 T:140277129455680 INFO: load fonts for skin... 19:11:23.762 T:140277129455680 INFO: Loading fonts from /usr/share/kodi/addons/skin.estuary/xml/Font.xml 19:11:23.799 T:140277129455680 DEBUG: LocalizeStrings: loaded 132 strings from file /usr/share/kodi/addons/skin.estuary/language/resource.language.en_gb/strings.po 19:11:23.799 T:140277129455680 INFO: Loading skin includes from /usr/share/kodi/addons/skin.estuary/xml/Includes.xml 19:11:23.864 T:140277127931648 INFO: Detecting DVD-ROM media filesystem... 19:11:23.869 T:140277127931648 INFO: CD Analysis Report 19:11:23.869 T:140277127931648 INFO: __________________________________ 19:11:23.871 T:140277127931648 INFO: CD-ROM with ISO 9660 filesystem 19:11:23.871 T:140277127931648 INFO: ISO 9660: 0 blocks, label 19:11:23.871 T:140277127931648 INFO: Tracks overall:1; Audio tracks:0; Data tracks:1 19:11:23.911 T:140277129455680 INFO: load new skin... 19:11:23.911 T:140277129455680 INFO: Loading user windows, path /usr/share/kodi/addons/skin.estuary/xml 19:11:23.930 T:140277129455680 DEBUG: Load Skin XML: 20.02ms 19:11:23.931 T:140277129455680 INFO: initialize new skin... 19:11:23.931 T:140277129455680 DEBUG: guilib: Fill viewport on change for solving rendering passes 19:11:23.931 T:140277129455680 INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT 19:11:23.937 T:140277129455680 DEBUG: OpenBundle - Opened bundle /usr/share/kodi/addons/skin.estuary/media/Textures.xbt 19:11:23.939 T:140277129455680 INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT 19:11:23.943 T:140277129455680 INFO: Loading skin file: DialogNotification.xml, load type: LOAD_ON_GUI_INIT 19:11:23.947 T:140277129455680 INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT 19:11:23.965 T:140277129455680 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT 19:11:23.969 T:140277129455680 INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT 19:11:23.976 T:140277129455680 INFO: Loading resource://resource.uisounds.kodi/sounds.xml 19:11:24.037 T:140277129455680 INFO: skin loaded... 19:11:24.037 T:140277129455680 DEBUG: Activating window ID: 12997 19:11:24.038 T:140277129455680 DEBUG: ------ Window Init () ------ 19:11:24.038 T:140277129455680 INFO: load splash image: /usr/share/kodi/media/Splash.png 19:11:24.085 T:140277129455680 DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting 19:11:24.085 T:140277129455680 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList" 19:11:24.085 T:140277129455680 INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting") 19:11:24.086 T:140277129455680 INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting 19:11:24.160 T:140277129455680 INFO: JSONRPC v8.0.0: Successfully initialized 19:11:24.160 T:140277129455680 DEBUG: ADDON: Starting service addons. 19:11:24.165 T:140275645150976 DEBUG: Thread LanguageInvoker start, auto delete: false 19:11:24.165 T:140277129455680 DEBUG: Activating window ID: 12999 19:11:24.165 T:140275645150976 INFO: initializing python engine. 19:11:24.165 T:140277129455680 DEBUG: ------ Window Init (Startup.xml) ------ 19:11:24.165 T:140277129455680 INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME 19:11:24.168 T:140277129455680 DEBUG: Activating window ID: 10000 19:11:24.168 T:140277129455680 DEBUG: ------ Window Deinit (Startup.xml) ------ 19:11:24.168 T:140277129455680 DEBUG: ------ Window Init (Home.xml) ------ 19:11:24.168 T:140277129455680 INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY 19:11:24.492 T:140277129455680 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing.. 19:11:24.493 T:140277129455680 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing.. 19:11:24.493 T:140277129455680 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing.. 19:11:24.493 T:140277129455680 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing.. 19:11:24.493 T:140277129455680 DEBUG: CDirectoryProvider[library://video/]: refreshing.. 19:11:24.493 T:140277129455680 DEBUG: CDirectoryProvider[sources://video/]: refreshing.. 19:11:24.494 T:140277129455680 DEBUG: CDirectoryProvider[special://videoplaylists/]: refreshing.. 19:11:24.494 T:140277129455680 DEBUG: CDirectoryProvider[favourites://]: refreshing.. 19:11:24.494 T:140277129455680 DEBUG: CDirectoryProvider[sources://pictures/]: refreshing.. 19:11:24.505 T:140275636758272 DEBUG: Thread JobWorker start, auto delete: true 19:11:24.508 T:140277129455680 DEBUG: ContextMenuManager: addon menus reloaded. 19:11:24.508 T:140277129455680 INFO: removing tempfiles 19:11:24.511 T:140277129455680 DEBUG: ADDON: Starting service addons. 19:11:24.532 T:140277129455680 DEBUG: CRepositoryUpdater: previous update at 04/30/2017 10:44:55 AM, next at 05/01/2017 10:44:55 AM 19:11:24.532 T:140277129455680 NOTICE: initialize done 19:11:24.532 T:140277129455680 NOTICE: Running the application... 19:11:24.535 T:140275628365568 DEBUG: Thread Timer start, auto delete: false 19:11:24.538 T:140277129455680 DEBUG: no profile autoexec.py (/home/ondrej/.kodi/userdata/autoexec.py) found, skipping 19:11:24.539 T:140277129455680 DEBUG: NetworkMessage - Starting network services 19:11:24.565 T:140277129455680 DEBUG: CZeroconfAvahi::clientCallback: client is up and running 19:11:24.566 T:140277129455680 NOTICE: starting zeroconf publishing 19:11:24.566 T:140277129455680 NOTICE: starting upnp client 19:11:24.570 T:140275611580160 DEBUG: Thread JobWorker start, auto delete: true 19:11:24.574 T:140275611580160 DEBUG: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/) 19:11:24.574 T:140275611580160 DEBUG: Getting Directory (special://profile/playlists/video) 19:11:24.577 T:140275611580160 DEBUG: Getting Directory (special://profile/playlists/mixed) 19:11:24.587 T:140275611580160 DEBUG: CMultiPathDirectory::MergeItems, items = 0 19:11:24.588 T:140275611580160 DEBUG: CFavourites::Load - no system favourites found, skipping 19:11:24.589 T:140275611580160 DEBUG: CFavourites::Load - no userdata favourites found, skipping 19:11:24.611 T:140275636758272 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update 19:11:24.623 T:140277129455680 INFO: JSONRPC Server: Successfully initialized 19:11:24.631 T:140275217319680 DEBUG: Thread TCPServer start, auto delete: false 19:11:24.641 T:140275225712384 DEBUG: Thread EventServer start, auto delete: false 19:11:24.641 T:140275225712384 NOTICE: ES: Starting UDP Event server on port 9777 19:11:24.641 T:140275225712384 NOTICE: UDP: Listening on port 9777 (ipv6 : false) 19:11:24.656 T:140276517562112 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:Kodi (atom) port:9090 19:11:24.656 T:140276517562112 DEBUG: CZeroconfAvahi::addService() named: Kodi (atom) type: _xbmc-jsonrpc._tcp port:9090 19:11:24.662 T:140275645150976 DEBUG: CPythonInvoker(0, /home/ondrej/.kodi/addons/service.xbmc.versioncheck/service.py): start processing 19:11:24.670 T:140276517562112 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:Kodi (atom) port:9777 19:11:24.670 T:140276517562112 DEBUG: CZeroconfAvahi::addService() named: Kodi (atom) type: _xbmc-events._udp port:9777 19:11:24.715 T:140275636758272 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 19:11:24.719 T:140275636758272 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 19:11:25.039 T:140275645150976 DEBUG: -->Python Interpreter Initialized<-- 19:11:25.039 T:140275645150976 DEBUG: CPythonInvoker(0, /home/ondrej/.kodi/addons/service.xbmc.versioncheck/service.py): the source file to load is "/home/ondrej/.kodi/addons/service.xbmc.versioncheck/service.py" 19:11:25.039 T:140275645150976 DEBUG: CPythonInvoker(0, /home/ondrej/.kodi/addons/service.xbmc.versioncheck/service.py): setting the Python path to /home/ondrej/.kodi/addons/service.xbmc.versioncheck:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gst-0.10:/usr/lib/python2.7/dist-packages/gtk-2.0:/usr/lib/pymodules/python2.7 19:11:25.039 T:140275645150976 DEBUG: CPythonInvoker(0, /home/ondrej/.kodi/addons/service.xbmc.versioncheck/service.py): entering source directory /home/ondrej/.kodi/addons/service.xbmc.versioncheck 19:11:25.039 T:140275645150976 DEBUG: CPythonInvoker(0, /home/ondrej/.kodi/addons/service.xbmc.versioncheck/service.py): instantiating addon using automatically obtained id of "service.xbmc.versioncheck" dependent on version 2.1.0 of the xbmc.python api 19:11:25.092 T:140277129455680 DEBUG: ------ Window Init () ------ 19:11:25.099 T:140275636758272 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update 19:11:25.170 T:140275636758272 DEBUG: RunQuery took 3 ms for 0 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 10 19:11:25.188 T:140275636758272 DEBUG: RunQuery took 17 ms for 0 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 10 19:11:25.195 T:140275636758272 DEBUG: RunQuery took 5 ms for 0 items query: select * from musicvideo_view ORDER BY dateAdded desc, idMVideo desc LIMIT 10 19:11:25.212 T:140275636758272 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update 19:11:25.220 T:140275636758272 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 <> 'Various artists') 19:11:25.294 T:140277129455680 DEBUG: UPower: Received an unknown signal NameAcquired 19:11:25.357 T:140275645150976 DEBUG: Version Check: Version 0.3.22 started 19:11:25.463 T:140275619972864 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established 19:11:27.472 T:140277127931648 DEBUG: Previous line repeats 1 times. 19:11:27.472 T:140277127931648 INFO: Using protocol iso9660:// 19:11:27.472 T:140277127931648 INFO: Disc label: 19:11:27.755 T:140277129455680 DEBUG: ------ Window Init (Pointer.xml) ------ 19:11:30.438 T:140275645150976 DEBUG: Version Check: Version installed {u'major': 17, u'tag': u'stable', u'minor': 1, u'revision': u'20170320-nogitfound'} 19:11:30.438 T:140275645150976 DEBUG: Version Check: Version available {u'major': u'17', u'extrainfo': u'final', u'tagversion': u'', u'tag': u'stable', u'addon_support': u'yes', u'minor': u'1', u'revision': u'20170320-fc1619b'} 19:11:30.438 T:140275645150976 DEBUG: Version Check: There is no newer stable available 19:11:30.439 T:140275645150976 INFO: CPythonInvoker(0, /home/ondrej/.kodi/addons/service.xbmc.versioncheck/service.py): script successfully run 19:11:30.455 T:140275645150976 INFO: Python script stopped 19:11:30.456 T:140275645150976 DEBUG: Thread LanguageInvoker 140275645150976 terminating 19:11:32.974 T:140277129455680 DEBUG: ProcessMouse: trying mouse action leftclick 19:11:32.983 T:140277129455680 DEBUG: GetDiskUniqueId: Trying to retrieve ID for path /dev/cdrom 19:11:32.983 T:140277129455680 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/VideoPlayer/libdvdnav-x86_64-linux.so) 19:11:32.983 T:140277129455680 DEBUG: Loading: /usr/lib/x86_64-linux-gnu/kodi/system/players/VideoPlayer/libdvdnav-x86_64-linux.so 19:11:32.986 T:140277129455680 INFO: msg: libdvdnav: Using dvdnav version 5.0.4 19:11:33.714 T:140277129455680 DEBUG: libdvdnav: DVD Title: 19:11:33.714 T:140277129455680 DEBUG: msg: I 19:11:33.714 T:140277129455680 DEBUG: msg: K 19:11:33.714 T:140277129455680 DEBUG: msg: A 19:11:33.714 T:140277129455680 DEBUG: msg: R 19:11:33.714 T:140277129455680 DEBUG: msg: I 19:11:33.714 T:140277129455680 DEBUG: msg: E 19:11:33.714 T:140277129455680 DEBUG: msg: _ 19:11:33.714 T:140277129455680 DEBUG: msg: X 19:11:33.715 T:140277129455680 DEBUG: msg: B 19:11:33.715 T:140277129455680 DEBUG: msg: _ 19:11:33.715 T:140277129455680 DEBUG: msg: 1 19:11:33.715 T:140277129455680 DEBUG: libdvdnav: DVD Serial Number: 19:11:33.715 T:140277129455680 DEBUG: msg: 3 19:11:33.715 T:140277129455680 DEBUG: msg: 4 19:11:33.715 T:140277129455680 DEBUG: Previous line repeats 1 times. 19:11:33.715 T:140277129455680 DEBUG: msg: A 19:11:33.715 T:140277129455680 DEBUG: msg: B 19:11:33.715 T:140277129455680 DEBUG: msg: 8 19:11:33.715 T:140277129455680 DEBUG: msg: 3 19:11:33.715 T:140277129455680 DEBUG: msg: 6 19:11:33.715 T:140277129455680 DEBUG: libdvdnav: DVD Title (Alternative): 19:11:33.715 T:140277129455680 DEBUG: msg: I 19:11:33.715 T:140277129455680 DEBUG: msg: K 19:11:33.715 T:140277129455680 DEBUG: msg: A 19:11:33.715 T:140277129455680 DEBUG: msg: R 19:11:33.715 T:140277129455680 DEBUG: msg: I 19:11:33.715 T:140277129455680 DEBUG: msg: E 19:11:33.715 T:140277129455680 DEBUG: msg: _ 19:11:33.715 T:140277129455680 DEBUG: msg: X 19:11:33.715 T:140277129455680 DEBUG: msg: B 19:11:33.715 T:140277129455680 DEBUG: msg: _ 19:11:33.715 T:140277129455680 DEBUG: msg: 1 19:11:33.715 T:140277129455680 DEBUG: msg: 19:11:33.716 T:140277129455680 INFO: msg: libdvdnav: vm: DVD Title: IKARIE_XB_1 19:11:33.716 T:140277129455680 INFO: msg: libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 19:11:33.716 T:140277129455680 INFO: msg: 1 19:11:33.716 T:140277129455680 INFO: msg: 2 19:11:33.716 T:140277129455680 INFO: msg: 3 19:11:33.716 T:140277129455680 INFO: msg: 4 19:11:33.716 T:140277129455680 INFO: msg: 5 19:11:33.716 T:140277129455680 INFO: msg: 6 19:11:33.716 T:140277129455680 INFO: msg: 7 19:11:33.716 T:140277129455680 INFO: msg: 8 19:11:33.716 T:140277129455680 DEBUG: msg: 19:11:33.716 T:140277129455680 DEBUG: Previous line repeats 1 times. 19:11:33.716 T:140277129455680 DEBUG: libdvdread: Attempting to retrieve all CSS keys 19:11:33.716 T:140277129455680 DEBUG: libdvdread: This can take a _long_ time, please be patient 19:11:33.716 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000195 19:11:33.716 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.716 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x0000022b 19:11:33.716 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.716 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x00023128 19:11:33.716 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.716 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x000241ff 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x0002424c 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_03_0.VOB at 0x001f9a79 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x001f9ac6 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_04_0.VOB at 0x001fc1b8 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x001fc205 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_05_0.VOB at 0x00244e76 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x00244ec3 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_06_0.VOB at 0x00257e10 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x00257e5d 19:11:33.717 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.718 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_07_0.VOB at 0x0026044b 19:11:33.718 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.718 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x00260498 19:11:33.718 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.718 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_08_0.VOB at 0x00260eb1 19:11:33.718 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.718 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x00260efe 19:11:33.718 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.718 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_09_0.VOB at 0x002b6f3a 19:11:33.718 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.718 T:140277129455680 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_09_1.VOB at 0x002b6f87 19:11:33.718 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.718 T:140277129455680 INFO: msg: libdvdread: Found 9 VTS's 19:11:33.718 T:140277129455680 INFO: msg: libdvdread: Elapsed time 0 19:11:33.718 T:140277129455680 DEBUG: Open - Setting region mask ff 19:11:33.892 T:140277129455680 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:33.892 T:140277129455680 DEBUG: libdvdnav: Menu Languages available: 19:11:33.892 T:140277129455680 INFO: msg: cs 19:11:33.892 T:140277129455680 DEBUG: msg: 19:11:33.892 T:140277129455680 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:33.892 T:140277129455680 DEBUG: libdvdnav: Menu Languages available: 19:11:33.892 T:140277129455680 INFO: msg: cs 19:11:33.892 T:140277129455680 DEBUG: msg: 19:11:33.892 T:140277129455680 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:33.892 T:140277129455680 DEBUG: libdvdnav: Menu Languages available: 19:11:33.892 T:140277129455680 INFO: msg: cs 19:11:33.893 T:140277129455680 DEBUG: msg: 19:11:33.893 T:140277129455680 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:33.893 T:140277129455680 DEBUG: libdvdnav: Menu Languages available: 19:11:33.893 T:140277129455680 INFO: msg: cs 19:11:33.893 T:140277129455680 DEBUG: msg: 19:11:33.896 T:140277129455680 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:33.896 T:140277129455680 DEBUG: libdvdnav: Menu Languages available: 19:11:33.896 T:140277129455680 INFO: msg: cs 19:11:33.896 T:140277129455680 DEBUG: msg: 19:11:33.896 T:140277129455680 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:33.896 T:140277129455680 DEBUG: libdvdnav: Menu Languages available: 19:11:33.896 T:140277129455680 INFO: msg: cs 19:11:33.896 T:140277129455680 DEBUG: msg: 19:11:33.896 T:140277129455680 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:33.896 T:140277129455680 DEBUG: libdvdnav: Menu Languages available: 19:11:33.896 T:140277129455680 INFO: msg: cs 19:11:33.896 T:140277129455680 DEBUG: msg: 19:11:33.896 T:140277129455680 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:33.896 T:140277129455680 DEBUG: libdvdnav: Menu Languages available: 19:11:33.896 T:140277129455680 INFO: msg: cs 19:11:33.896 T:140277129455680 DEBUG: msg: 19:11:33.896 T:140277129455680 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:33.896 T:140277129455680 DEBUG: libdvdnav: Menu Languages available: 19:11:33.896 T:140277129455680 INFO: msg: cs 19:11:33.896 T:140277129455680 DEBUG: msg: 19:11:33.896 T:140277129455680 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:33.896 T:140277129455680 DEBUG: libdvdnav: Menu Languages available: 19:11:33.896 T:140277129455680 INFO: msg: cs 19:11:33.896 T:140277129455680 DEBUG: msg: 19:11:33.897 T:140277129455680 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:33.897 T:140277129455680 DEBUG: libdvdnav: Menu Languages available: 19:11:33.897 T:140277129455680 INFO: msg: cs 19:11:33.897 T:140277129455680 DEBUG: msg: 19:11:33.897 T:140277129455680 DEBUG: GetDiskUniqueId: Got ID removable://IKARIE_XB_1_344AB836 for DVD disk 19:11:33.921 T:140277129455680 DEBUG: Loading settings for /media/ondrej/IKARIE_XB_1/VIDEO_TS/VIDEO_TS.IFO 19:11:33.926 T:140276668229376 DEBUG: GetMovieId (removable://IKARIE_XB_1_344AB836/), query = select idMovie from movie where idFile=2 19:11:33.929 T:140276668229376 DEBUG: GetEpisodeId (removable://IKARIE_XB_1_344AB836/), query = select idEpisode from episode where idFile=2 19:11:33.930 T:140276668229376 DEBUG: GetMusicVideoId (removable://IKARIE_XB_1_344AB836/), query = select idMVideo from musicvideo where idFile=2 19:11:33.937 T:140276668229376 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 19:11:33.938 T:140276668229376 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 19:11:33.943 T:140277129455680 DEBUG: CPlayerCoreFactory::GetPlayers(/media/ondrej/IKARIE_XB_1/VIDEO_TS/VIDEO_TS.IFO) 19:11:33.943 T:140277129455680 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 19:11:33.943 T:140277129455680 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 19:11:33.943 T:140277129455680 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 19:11:33.943 T:140277129455680 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 19:11:33.943 T:140277129455680 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 19:11:33.943 T:140277129455680 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 19:11:33.943 T:140277129455680 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 19:11:33.943 T:140277129455680 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 19:11:33.943 T:140277129455680 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 19:11:33.943 T:140277129455680 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 19:11:33.943 T:140277129455680 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 19:11:33.943 T:140277129455680 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 19:11:33.943 T:140277129455680 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 19:11:33.944 T:140277129455680 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 19:11:33.944 T:140277129455680 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 19:11:33.944 T:140277129455680 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 19:11:33.944 T:140277129455680 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 19:11:33.951 T:140277129455680 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData 19:11:33.951 T:140277129455680 NOTICE: VideoPlayer: Opening: /media/ondrej/IKARIE_XB_1/VIDEO_TS/VIDEO_TS.IFO 19:11:33.951 T:140277129455680 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 19:11:33.951 T:140277129455680 DEBUG: LinuxRendererGL: Cleaning up GL resources 19:11:33.952 T:140277129455680 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 19:11:33.952 T:140275645150976 DEBUG: Thread VideoPlayer start, auto delete: false 19:11:33.952 T:140275645150976 NOTICE: Creating InputStream 19:11:33.955 T:140275645150976 INFO: msg: libdvdnav: Using dvdnav version 5.0.4 19:11:33.955 T:140275645150976 INFO: dll_fopen - something opened the mount file, let's hope it knows what it's doing 19:11:33.955 T:140275645150976 INFO: msg: libdvdread: Attempting to use device /dev/sr0 mounted on /media/ondrej/IKARIE_XB_1 for CSS authentication 19:11:34.452 T:140277129455680 DEBUG: ------ Window Init (DialogBusy.xml) ------ 19:11:34.457 T:140277129455680 DEBUG: ------ Window Deinit (Pointer.xml) ------ 19:11:34.852 T:140275645150976 DEBUG: libdvdnav: Can't read name block. Probably not a DVD-ROM device. 19:11:34.852 T:140275645150976 DEBUG: libdvdnav: vm: dvd_read_name failed 19:11:34.852 T:140275645150976 INFO: msg: libdvdnav: vm: DVD Title: IKARIE_XB_1 19:11:34.852 T:140275645150976 INFO: msg: libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 19:11:34.852 T:140275645150976 INFO: msg: 1 19:11:34.853 T:140275645150976 INFO: msg: 2 19:11:34.853 T:140275645150976 INFO: msg: 3 19:11:34.853 T:140275645150976 INFO: msg: 4 19:11:34.853 T:140275645150976 INFO: msg: 5 19:11:34.853 T:140275645150976 INFO: msg: 6 19:11:34.853 T:140275645150976 INFO: msg: 7 19:11:34.853 T:140275645150976 INFO: msg: 8 19:11:34.853 T:140275645150976 DEBUG: msg: 19:11:34.853 T:140275645150976 DEBUG: Previous line repeats 1 times. 19:11:34.853 T:140275645150976 DEBUG: libdvdread: Attempting to retrieve all CSS keys 19:11:34.853 T:140275645150976 DEBUG: libdvdread: This can take a _long_ time, please be patient 19:11:34.853 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000195 19:11:34.853 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.853 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x0000022b 19:11:34.853 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.853 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x00023128 19:11:34.853 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x000241ff 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x0002424c 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_03_0.VOB at 0x001f9a79 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x001f9ac6 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_04_0.VOB at 0x001fc1b8 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x001fc205 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_05_0.VOB at 0x00244e76 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x00244ec3 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_06_0.VOB at 0x00257e10 19:11:34.854 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.855 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x00257e5d 19:11:34.855 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.855 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_07_0.VOB at 0x0026044b 19:11:34.855 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.855 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x00260498 19:11:34.855 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.855 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_08_0.VOB at 0x00260eb1 19:11:34.855 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.855 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x00260efe 19:11:34.855 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.855 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_09_0.VOB at 0x002b6f3a 19:11:34.855 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.855 T:140275645150976 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_09_1.VOB at 0x002b6f87 19:11:34.855 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.855 T:140275645150976 INFO: msg: libdvdread: Found 9 VTS's 19:11:34.855 T:140275645150976 INFO: msg: libdvdread: Elapsed time 0 19:11:34.855 T:140275645150976 DEBUG: Open - Setting region mask ff 19:11:34.859 T:140275645150976 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:34.859 T:140275645150976 DEBUG: libdvdnav: Menu Languages available: 19:11:34.859 T:140275645150976 INFO: msg: cs 19:11:34.859 T:140275645150976 DEBUG: msg: 19:11:34.859 T:140275645150976 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:34.859 T:140275645150976 DEBUG: libdvdnav: Menu Languages available: 19:11:34.859 T:140275645150976 INFO: msg: cs 19:11:34.859 T:140275645150976 DEBUG: msg: 19:11:34.859 T:140275645150976 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:34.859 T:140275645150976 DEBUG: libdvdnav: Menu Languages available: 19:11:34.859 T:140275645150976 INFO: msg: cs 19:11:34.859 T:140275645150976 DEBUG: msg: 19:11:34.859 T:140275645150976 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:34.860 T:140275645150976 DEBUG: libdvdnav: Menu Languages available: 19:11:34.860 T:140275645150976 INFO: msg: cs 19:11:34.860 T:140275645150976 DEBUG: msg: 19:11:34.862 T:140275645150976 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:34.862 T:140275645150976 DEBUG: libdvdnav: Menu Languages available: 19:11:34.862 T:140275645150976 INFO: msg: cs 19:11:34.863 T:140275645150976 DEBUG: msg: 19:11:34.863 T:140275645150976 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:34.863 T:140275645150976 DEBUG: libdvdnav: Menu Languages available: 19:11:34.863 T:140275645150976 INFO: msg: cs 19:11:34.863 T:140275645150976 DEBUG: msg: 19:11:34.863 T:140275645150976 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:34.863 T:140275645150976 DEBUG: libdvdnav: Menu Languages available: 19:11:34.863 T:140275645150976 INFO: msg: cs 19:11:34.863 T:140275645150976 DEBUG: msg: 19:11:34.863 T:140275645150976 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:34.863 T:140275645150976 DEBUG: libdvdnav: Menu Languages available: 19:11:34.863 T:140275645150976 INFO: msg: cs 19:11:34.863 T:140275645150976 DEBUG: msg: 19:11:34.863 T:140275645150976 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:34.863 T:140275645150976 DEBUG: libdvdnav: Menu Languages available: 19:11:34.863 T:140275645150976 INFO: msg: cs 19:11:34.863 T:140275645150976 DEBUG: msg: 19:11:34.863 T:140275645150976 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:34.863 T:140275645150976 DEBUG: libdvdnav: Menu Languages available: 19:11:34.863 T:140275645150976 INFO: msg: cs 19:11:34.863 T:140275645150976 DEBUG: msg: 19:11:34.863 T:140275645150976 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 19:11:34.863 T:140275645150976 DEBUG: libdvdnav: Menu Languages available: 19:11:34.863 T:140275645150976 INFO: msg: cs 19:11:34.863 T:140275645150976 DEBUG: msg: 19:11:34.864 T:140275645150976 NOTICE: VideoPlayer: playing a file with menu's 19:11:35.079 T:140275645150976 NOTICE: Creating Demuxer 19:11:35.079 T:140275645150976 DEBUG: DVDNAV_HOP_CHANNEL 19:11:35.080 T:140275645150976 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 19:11:35.080 T:140275645150976 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 19:11:35.080 T:140275645150976 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 19:11:35.080 T:140275645150976 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 19:11:35.080 T:140275645150976 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 19:11:36.080 T:140275645150976 DEBUG: CDVDMsgGeneralSynchronize - global timeout 19:11:36.080 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 2 19:11:36.080 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:11:36.082 T:140275645150976 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 4 19:11:36.082 T:140275645150976 DEBUG: - DVDNAV_VTS_CHANGE (HOLDING) 19:11:36.082 T:140275645150976 DEBUG: Open - avformat_find_stream_info starting 19:11:36.082 T:140275645150976 WARNING: could not find codec parameters for /media/ondrej/IKARIE_XB_1/VIDEO_TS/VIDEO_TS.IFO 19:11:36.082 T:140275645150976 DEBUG: Open - av_find_stream_info finished 19:11:36.082 T:140275645150976 INFO: ffmpeg[7F9477FFF700]: Input #0, mpeg, /media/ondrej/IKARIE_XB_1/VIDEO_TS/VIDEO_TS.IF': 19:11:36.082 T:140275645150976 INFO: ffmpeg[7F9477FFF700]: Duration: N/A, bitrate: N/A 19:11:36.082 T:140275645150976 INFO: GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 19:11:36.082 T:140275645150976 WARNING: CDVDInputStreamNavigator::GetVideoResolution - Failed to get resolution (Cannot get current position) 19:11:36.082 T:140275645150976 NOTICE: Opening stream: 1 source: 512 19:11:36.083 T:140275645150976 INFO: GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 19:11:36.083 T:140275645150976 NOTICE: Creating video codec with codec id: 0 19:11:36.083 T:140275645150976 DEBUG: FactoryCodec - Video: - Opening 19:11:36.083 T:140275645150976 DEBUG: CDVDVideoCodecFFmpeg::Open() Unable to find codec 0 19:11:36.083 T:140275645150976 DEBUG: FactoryCodec - Video: - Failed 19:11:36.083 T:140275645150976 ERROR: Unsupported video codec 19:11:36.083 T:140275645150976 NOTICE: Opening stream: 0 source: 512 19:11:36.083 T:140275645150976 NOTICE: Finding audio codec for: 0 19:11:36.083 T:140275645150976 DEBUG: FactoryCodec - Audio: passthrough - Opening 19:11:36.083 T:140275645150976 DEBUG: FactoryCodec - Audio: passthrough - Failed 19:11:36.083 T:140275645150976 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 19:11:36.084 T:140275645150976 DEBUG: CDVDAudioCodecFFmpeg::Open() Unable to find codec 0 19:11:36.084 T:140275645150976 DEBUG: FactoryCodec - Audio: FFmpeg - Failed 19:11:36.084 T:140275645150976 ERROR: Unsupported audio codec 19:11:36.084 T:140275645150976 NOTICE: Opening stream: 0 source: 512 19:11:36.087 T:140275645150976 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opening 19:11:36.087 T:140275645150976 DEBUG: Open - Unable to find codec 0 19:11:36.087 T:140275645150976 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Failed 19:11:36.087 T:140275645150976 ERROR: OpenStream - Unable to init overlay codec 19:11:36.087 T:140275645150976 NOTICE: Opening stream: 1 source: 512 19:11:36.087 T:140275645150976 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opening 19:11:36.087 T:140275645150976 DEBUG: Open - Unable to find codec 0 19:11:36.087 T:140275645150976 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Failed 19:11:36.087 T:140275645150976 ERROR: OpenStream - Unable to init overlay codec 19:11:36.089 T:140275645150976 DEBUG: OnPlayBackStarted: play state was 1, starting 1 19:11:36.089 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 0 19:11:36.089 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:11:36.089 T:140275645150976 NOTICE: VideoPlayer: next stream, wait for old streams to be finished 19:11:36.089 T:140275645150976 NOTICE: Creating Demuxer 19:11:36.089 T:140275645150976 DEBUG: DVDNAV_VTS_CHANGE 19:11:36.089 T:140275645150976 INFO: GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 19:11:36.090 T:140275645150976 INFO: Previous line repeats 1 times. 19:11:36.090 T:140275645150976 WARNING: CDVDInputStreamNavigator::GetVideoResolution - Failed to get resolution (Cannot get current position) 19:11:36.090 T:140277129455680 DEBUG: PlayFile: OpenFile succeed, play state 2 19:11:36.090 T:140277129455680 DEBUG: OnPlayBackStarted: play state was 2, starting 0 19:11:36.091 T:140275645150976 DEBUG: ProcessBlock - Cell change: Title 2, Chapter 5 19:11:36.091 T:140275645150976 DEBUG: ProcessBlock - At position 30% inside the feature 19:11:36.091 T:140275645150976 DEBUG: DVDNAV_CELL_CHANGE 19:11:36.278 T:140275645150976 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:0 TO:1529720000 DIFF:1529720000 19:11:36.281 T:140275645150976 DEBUG: Open - avformat_find_stream_info starting 19:11:36.283 T:140276517562112 DEBUG: CGUIInfoManager::SetCurrentMovie(/media/ondrej/IKARIE_XB_1/VIDEO_TS/VIDEO_TS.IFO) 19:11:36.305 T:140276517562112 DEBUG: GetMovieId (removable://IKARIE_XB_1_344AB836/), query = select idMovie from movie where idFile=2 19:11:36.307 T:140276517562112 DEBUG: Previous line repeats 1 times. 19:11:36.307 T:140276517562112 DEBUG: GetEpisodeId (removable://IKARIE_XB_1_344AB836/), query = select idEpisode from episode where idFile=2 19:11:36.308 T:140276517562112 DEBUG: Previous line repeats 1 times. 19:11:36.309 T:140276517562112 DEBUG: GetMusicVideoId (removable://IKARIE_XB_1_344AB836/), query = select idMVideo from musicvideo where idFile=2 19:11:36.316 T:140276668229376 DEBUG: Previous line repeats 1 times. 19:11:36.316 T:140276668229376 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 19:11:36.316 T:140276668229376 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 19:11:36.332 T:140277129455680 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 19:11:36.601 T:140275645150976 DEBUG: Open - av_find_stream_info finished 19:11:36.602 T:140275645150976 INFO: ffmpeg[7F9477FFF700]: Input #0, mpeg, /media/ondrej/IKARIE_XB_1/VIDEO_TS/VIDEO_TS.IF': 19:11:36.602 T:140275645150976 INFO: ffmpeg[7F9477FFF700]: Duration: N/A, start: 1529.512000, bitrate: N/A 19:11:36.602 T:140275645150976 INFO: ffmpeg[7F9477FFF700]: Stream #0:0[0x1e0]: Video: mpeg2video (Main), yuv420p(tv), 720x576 [SAR 64:45 DAR 16:9], 25 fps, 25 tbr, 90k tbn, 50 tbc 19:11:36.602 T:140275645150976 INFO: ffmpeg[7F9477FFF700]: Stream #0:1[0x80]: Audio: ac3, 48000 Hz, stereo, fltp, 192 kb/s 19:11:36.602 T:140275645150976 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 19:11:36.602 T:140275645150976 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 19:11:36.603 T:140275645150976 INFO: GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 19:11:36.603 T:140275645150976 WARNING: CDVDInputStreamNavigator::GetVideoResolution - Failed to get resolution (Cannot get current position) 19:11:36.604 T:140275645150976 NOTICE: Opening stream: 1 source: 256 19:11:36.604 T:140275645150976 NOTICE: Finding audio codec for: 86019 19:11:36.605 T:140275645150976 DEBUG: FactoryCodec - Audio: passthrough - Opening 19:11:36.605 T:140275645150976 DEBUG: FactoryCodec - Audio: passthrough - Failed 19:11:36.605 T:140275645150976 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 19:11:36.606 T:140275645150976 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 19:11:36.607 T:140275645150976 NOTICE: Creating audio thread 19:11:36.608 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 1 19:11:36.608 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:11:36.608 T:140275645150976 NOTICE: Opening stream: 0 source: 256 19:11:36.608 T:140275645150976 INFO: GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 19:11:36.608 T:140275645150976 NOTICE: Creating video codec with codec id: 2 19:11:36.608 T:140275645150976 DEBUG: FactoryCodec - Video: - Opening 19:11:36.609 T:140275645150976 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video 19:11:36.609 T:140275645150976 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg2video 19:11:36.609 T:140275645150976 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened 19:11:36.609 T:140275645150976 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 2 19:11:36.609 T:140275645150976 NOTICE: Creating video thread 19:11:36.616 T:140275199145728 DEBUG: Thread VideoPlayerAudio start, auto delete: false 19:11:36.616 T:140275199145728 NOTICE: running thread: CVideoPlayerAudio::Process() 19:11:36.616 T:140275199145728 DEBUG: CDVDAudio::Pause - pausing audio stream 19:11:36.620 T:140275190753024 DEBUG: Thread VideoPlayerVideo start, auto delete: false 19:11:36.620 T:140275190753024 NOTICE: running thread: video_thread 19:11:36.620 T:140275190753024 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 19:11:36.620 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:11:36.620 T:140275190753024 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(720x576) 19:11:36.621 T:140275190753024 NOTICE: VDPAU::CreateContext - creating decoder context 19:11:36.621 T:140275199145728 ERROR: ffmpeg[7F945D6A7700]: [ac3] frame sync error 19:11:36.622 T:140275199145728 ERROR: CVideoPlayerAudio::DecodeFrame - Decode Error. Skipping audio packet (-1094995529) 19:11:36.624 T:140275199145728 NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, no pass-through) 19:11:36.625 T:140275199145728 DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback 19:11:36.628 T:140276576282368 INFO: CActiveAESink::OpenSink - initialize sink 19:11:36.780 T:140276576282368 DEBUG: CActiveAESink::OpenSink - trying to open device PULSE:Default 19:11:36.786 T:140276576282368 DEBUG: PulseAudio: Context authorizing 19:11:36.795 T:140276576282368 DEBUG: PulseAudio: Context setting name 19:11:36.800 T:140276576282368 DEBUG: PulseAudio: Context ready 19:11:36.800 T:140276576282368 DEBUG: PULSE does not support format: AE_FMT_FLOATP - will fallback to AE_FMT_FLOAT 19:11:36.903 T:140276576282368 DEBUG: PulseAudio: Stream ready 19:11:36.904 T:140276576282368 NOTICE: PulseAudio: Opened device Default in pcm mode with Buffersize 150 ms 19:11:36.913 T:140276576282368 DEBUG: CActiveAESink::OpenSink - PULSE Initialized: 19:11:36.913 T:140276576282368 DEBUG: Output Device : Default 19:11:36.913 T:140276576282368 DEBUG: Sample Rate : 48000 19:11:36.913 T:140276576282368 DEBUG: Sample Format : AE_FMT_FLOAT 19:11:36.914 T:140276576282368 DEBUG: Channel Count : 2 19:11:36.914 T:140276576282368 DEBUG: Channel Layout: FL,FR 19:11:36.914 T:140276576282368 DEBUG: Frames : 2400 19:11:36.914 T:140276576282368 DEBUG: Frame Size : 8 19:11:36.925 T:140276584675072 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 19:11:36.935 T:140275645150976 DEBUG: Previous line repeats 1 times. 19:11:36.935 T:140275645150976 DEBUG: CVideoPlayer::HandleMessages - player started 1 19:11:37.234 T:140275190753024 NOTICE: vdp_device = 0x00000001 vdp_st = 0x00000000 19:11:37.234 T:140275190753024 NOTICE: VDPAU Decoder capabilities: 19:11:37.234 T:140275190753024 NOTICE: name level macbs width height 19:11:37.234 T:140275190753024 NOTICE: ------------------------------------ 19:11:37.234 T:140275190753024 NOTICE: MPEG1 0 8192 2048 2048 19:11:37.234 T:140275190753024 NOTICE: MPEG2_SIMPLE 3 8192 2048 2048 19:11:37.234 T:140275190753024 NOTICE: MPEG2_MAIN 3 8192 2048 2048 19:11:37.234 T:140275190753024 NOTICE: H264_MAIN 41 8190 2032 2048 19:11:37.234 T:140275190753024 NOTICE: H264_HIGH 41 8190 2032 2048 19:11:37.235 T:140275190753024 NOTICE: VC1_SIMPLE 1 8190 2048 2048 19:11:37.235 T:140275190753024 NOTICE: VC1_MAIN 2 8190 2048 2048 19:11:37.235 T:140275190753024 NOTICE: VC1_ADVANCED 4 8190 2048 2048 19:11:37.235 T:140275190753024 NOTICE: ------------------------------------ 19:11:37.235 T:140275190753024 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_NOISE_REDUCTION 19:11:37.235 T:140275190753024 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_SHARPNESS 19:11:37.235 T:140275190753024 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL 19:11:37.235 T:140275190753024 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL_SPATIAL 19:11:37.235 T:140275190753024 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_INVERSE_TELECINE 19:11:37.345 T:140275190753024 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg2video-vdpau 19:11:37.351 T:140275190753024 DEBUG: CVDPAU::SetWidthHeight Setting OutWidth: 720 OutHeight: 576 19:11:37.351 T:140275190753024 NOTICE: (VDPAU) screenWidth:720 vidWidth:720 surfaceWidth:720 19:11:37.351 T:140275190753024 NOTICE: (VDPAU) screenHeight:576 vidHeight:576 surfaceHeight:576 19:11:37.791 T:140275038111488 DEBUG: Thread Vdpau Output start, auto delete: false 19:11:37.791 T:140275038111488 NOTICE: COutput::OnStartup: Output Thread created 19:11:37.840 T:140275038111488 NOTICE: VDPAU::COutput::CreateGlxContext - created context 19:11:37.841 T:140275038111488 NOTICE: VDPAU::COutput: vdpau gl interop initialized 19:11:37.841 T:140275027642112 DEBUG: Thread Vdpau Mixer start, auto delete: false 19:11:37.841 T:140275027642112 NOTICE: CMixer::OnStartup: Output Thread created 19:11:37.841 T:140275027642112 NOTICE: (VDPAU) Creating the video mixer 19:11:37.856 T:140275038111488 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 19:11:37.862 T:140275190753024 NOTICE: Previous line repeats 3 times. 19:11:37.862 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:11:37.885 T:140275038111488 DEBUG: Previous line repeats 5 times. 19:11:37.885 T:140275038111488 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 19:11:37.895 T:140275038111488 NOTICE: Previous line repeats 2 times. 19:11:37.895 T:140275038111488 NOTICE: VDPAU::COutput registered output surfaces 19:11:37.896 T:140275190753024 DEBUG: CRenderManager::Configure - change configuration. 720x576. display: 720x405. framerate: 25.00. format: VDPAU 19:11:37.897 T:140275038111488 NOTICE: VDPAU::COutput registered output surfaces 19:11:37.909 T:140277129455680 NOTICE: Previous line repeats 1 times. 19:11:37.909 T:140277129455680 DEBUG: DeleteRenderer - deleting renderer 19:11:37.909 T:140277129455680 DEBUG: LinuxRendererGL: Cleaning up GL resources 19:11:37.922 T:140277129455680 DEBUG: Previous line repeats 1 times. 19:11:37.923 T:140277129455680 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 19:11:37.923 T:140277129455680 NOTICE: GL: Using VDPAU render method 19:11:37.923 T:140277129455680 NOTICE: GL: NPOT texture support detected 19:11:37.923 T:140277129455680 NOTICE: GL: Using GL_ARB_pixel_buffer_object 19:11:37.923 T:140277129455680 NOTICE: Using GL_TEXTURE_2D 19:11:37.923 T:140277129455680 DEBUG: CRenderManager::Configure - 5 19:11:37.924 T:140277129455680 DEBUG: GL: Vertex Shader compilation log: 19:11:37.926 T:140277129455680 DEBUG: GL: Vertex Shader compiled successfully 19:11:37.926 T:140277129455680 DEBUG: GL: Pixel Shader compilation log: 19:11:37.926 T:140277129455680 DEBUG: GL: Fragment Shader compiled successfully 19:11:37.933 T:140275645150976 DEBUG: CVideoPlayer::HandleMessages - player started 2 19:11:37.933 T:140275645150976 DEBUG: VideoPlayer::Sync - Audio - pts: 1529608000.000000, cache: 282651.186413, totalcache: 550000.011921 19:11:37.933 T:140275645150976 DEBUG: VideoPlayer::Sync - Video - pts: 1529800000.000000, cache: 50000.000000, totalcache: 100000.000000 19:11:37.933 T:140275199145728 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(1529325348.813587) 19:11:37.933 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 0 19:11:37.933 T:140275190753024 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(1529325348.813587) 19:11:37.933 T:140275199145728 DEBUG: CDVDAudio::Resume - resume audio stream 19:11:37.934 T:140277129455680 DEBUG: Activating window ID: 12005 19:11:37.934 T:140277129455680 DEBUG: ------ Window Deinit (Home.xml) ------ 19:11:37.934 T:140277129455680 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 19:11:37.935 T:140277129455680 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY 19:11:37.938 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:11:37.938 T:140276584675072 DEBUG: ActiveAE - start sync of audio stream 19:11:37.938 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:11:37.946 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:11:37.949 T:140275645150976 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 2 19:11:37.950 T:140275645150976 NOTICE: Opening stream: 2 source: 256 19:11:37.951 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:11:37.951 T:140275645150976 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 3 19:11:37.952 T:140275038111488 NOTICE: VDPAU::COutput registered output surfaces 19:11:37.954 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:11:37.955 T:140275038111488 NOTICE: VDPAU::COutput registered output surfaces 19:11:37.956 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:11:37.957 T:140275038111488 NOTICE: VDPAU::COutput registered output surfaces 19:11:37.959 T:140275190753024 NOTICE: Previous line repeats 1 times. 19:11:37.959 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:11:38.043 T:140276584675072 DEBUG: Previous line repeats 1 times. 19:11:38.044 T:140276584675072 DEBUG: ActiveAE::SyncStream - average error of 20.754485, start adjusting 19:11:38.044 T:140276584675072 DEBUG: ActiveAE::SyncStream - average error 0.754485 below threshold of 30.000000 19:11:38.057 T:140277129455680 DEBUG: ------ Window Init (Pointer.xml) ------ 19:11:38.075 T:140277129455680 DEBUG: ------ Window Init (VideoOSD.xml) ------ 19:11:38.075 T:140277129455680 INFO: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY 19:11:38.122 T:140277129455680 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 19:11:38.124 T:140277129455680 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 19:11:38.124 T:140277129455680 INFO: Loading skin file: Custom_1109_TopBarOverlay.xml, load type: KEEP_IN_MEMORY 19:11:38.388 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:11:38.476 T:140275190753024 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 40000 19:11:38.816 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:11:39.063 T:140275199145728 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-38567.436849, adjusted:-38567.436849 19:11:39.248 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:11:40.474 T:140277129455680 INFO: Python, unloading python shared library because no scripts are running anymore 19:11:40.488 T:140277129455680 DEBUG: UnloadExtensionLibs, clearing python extension libraries 19:11:40.992 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:11:43.025 T:140275190753024 DEBUG: Previous line repeats 1 times. 19:11:43.025 T:140275190753024 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000 19:11:46.988 T:140277129455680 DEBUG: ------ Window Deinit (Pointer.xml) ------ 19:11:50.291 T:140277129455680 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 19:11:50.323 T:140277129455680 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 19:11:50.324 T:140277129455680 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 19:12:06.353 T:140275611580160 DEBUG: Thread JobWorker 140275611580160 terminating (autodelete) 19:12:06.353 T:140275636758272 DEBUG: Thread JobWorker 140275636758272 terminating (autodelete) 19:12:06.354 T:140276517562112 DEBUG: Thread JobWorker 140276517562112 terminating (autodelete) 19:12:22.714 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:12:29.691 T:140277129455680 DEBUG: ------ Window Init (Pointer.xml) ------ 19:12:29.706 T:140277129455680 DEBUG: ------ Window Init (VideoOSD.xml) ------ 19:12:29.716 T:140277129455680 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 19:12:29.717 T:140277129455680 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 19:12:32.672 T:140277129455680 DEBUG: ProcessMouse: unknown mouse command 57605 19:12:32.672 T:140277129455680 DEBUG: ProcessMouse: trying mouse action mousedrag 19:12:33.596 T:140275645150976 DEBUG: Previous line repeats 71 times. 19:12:33.596 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:12:33.607 T:140277129455680 DEBUG: ProcessMouse: trying mouse action mousedrag 19:12:33.972 T:140277129455680 DEBUG: Previous line repeats 8 times. 19:12:33.972 T:140277129455680 DEBUG: ProcessMouse: unknown mouse command 57606 19:12:33.973 T:140277129455680 DEBUG: ------ Window Deinit (Pointer.xml) ------ 19:12:34.938 T:140277129455680 DEBUG: ProcessMouse: trying mouse action leftclick 19:12:34.944 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 2 19:12:34.945 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:12:34.945 T:140275645150976 DEBUG: demuxer seek to: 2099976.000000 19:12:34.945 T:140275645150976 DEBUG: demuxer seek to: 2099976.000000, success 19:12:34.945 T:140275645150976 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 19:12:34.949 T:140275199145728 DEBUG: CDVDAudio::Pause - pausing audio stream 19:12:35.012 T:140275199145728 DEBUG: CDVDAudio::Flush - flush audio stream 19:12:35.012 T:140275199145728 DEBUG: CDVDAudio::Pause - pausing audio stream 19:12:35.438 T:140277129455680 DEBUG: CDVDMsgGeneralSynchronize - global timeout 19:12:35.443 T:140275199145728 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 19:12:35.445 T:140275190753024 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 19:12:35.446 T:140275645150976 DEBUG: CVideoPlayer - CDVDMsg::GENERAL_SYNCHRONIZE 19:12:35.446 T:140275645150976 DEBUG: DVDNAV_HOP_CHANNEL 19:12:35.446 T:140275645150976 DEBUG: ProcessBlock - Cell change: Title 2, Chapter 7 19:12:35.446 T:140275645150976 DEBUG: ProcessBlock - At position 42% inside the feature 19:12:35.446 T:140275645150976 DEBUG: DVDNAV_CELL_CHANGE 19:12:35.448 T:140276668229376 DEBUG: GetMovieId (removable://IKARIE_XB_1_344AB836/), query = select idMovie from movie where idFile=2 19:12:35.449 T:140276668229376 DEBUG: GetEpisodeId (removable://IKARIE_XB_1_344AB836/), query = select idEpisode from episode where idFile=2 19:12:35.450 T:140276668229376 DEBUG: GetMusicVideoId (removable://IKARIE_XB_1_344AB836/), query = select idMVideo from musicvideo where idFile=2 19:12:35.452 T:140276668229376 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc 19:12:35.453 T:140276668229376 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek 19:12:35.574 T:140275645150976 DEBUG: DVDNAV_NAV_PACKET (HOLDING) 19:12:35.676 T:140275645150976 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:1595000000 TO:2100760000 DIFF:505760000 19:12:35.700 T:140275190753024 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 19:12:35.700 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:12:35.706 T:140275199145728 DEBUG: Previous line repeats 6 times. 19:12:35.706 T:140275199145728 ERROR: ffmpeg[7F945D6A7700]: [ac3] frame sync error 19:12:35.706 T:140275199145728 ERROR: CVideoPlayerAudio::DecodeFrame - Decode Error. Skipping audio packet (-1094995529) 19:12:35.708 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:12:35.829 T:140275645150976 DEBUG: Previous line repeats 3 times. 19:12:35.829 T:140275645150976 DEBUG: CVideoPlayer::HandleMessages - player started 1 19:12:35.829 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:12:35.927 T:140275645150976 DEBUG: CVideoPlayer::HandleMessages - player started 2 19:12:35.927 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 3 19:12:35.927 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:12:35.927 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 0 19:12:35.927 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:12:35.927 T:140275645150976 DEBUG: VideoPlayer::Sync - Audio - pts: 2100775999.000000, cache: 288455.837570, totalcache: 550000.011921 19:12:35.928 T:140275645150976 DEBUG: VideoPlayer::Sync - Video - pts: 2100840000.000000, cache: 50000.000000, totalcache: 100000.000000 19:12:35.928 T:140275199145728 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(2100487543.162430) 19:12:35.928 T:140275199145728 DEBUG: CDVDAudio::Resume - resume audio stream 19:12:35.928 T:140276584675072 DEBUG: ActiveAE - start sync of audio stream 19:12:35.929 T:140275190753024 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(2100487543.162430) 19:12:35.929 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:12:36.091 T:140276584675072 DEBUG: Previous line repeats 1 times. 19:12:36.091 T:140276584675072 DEBUG: ActiveAE::SyncStream - average error of -25.886237, start adjusting 19:12:36.091 T:140276584675072 DEBUG: ActiveAE::SyncStream - average error -0.886237 below threshold of 30.000000 19:12:37.033 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:12:37.094 T:140275199145728 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-26202.184896, adjusted:-26202.184896 19:12:37.281 T:140277129455680 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 19:12:37.397 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:12:37.758 T:140277129455680 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 19:12:37.758 T:140277129455680 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 19:12:39.376 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:12:41.125 T:140275190753024 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000 19:12:43.469 T:140277129455680 DEBUG: ------ Window Init (Pointer.xml) ------ 19:12:43.485 T:140277129455680 DEBUG: ------ Window Init (VideoOSD.xml) ------ 19:12:43.495 T:140277129455680 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 19:12:43.496 T:140277129455680 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 19:12:48.050 T:140277129455680 DEBUG: ProcessMouse: trying mouse action leftclick 19:12:48.055 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 2 19:12:48.055 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:12:48.055 T:140275645150976 DEBUG: demuxer seek to: 2565774.000000 19:12:48.055 T:140275645150976 DEBUG: demuxer seek to: 2565774.000000, success 19:12:48.055 T:140275645150976 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 19:12:48.098 T:140275199145728 DEBUG: CDVDAudio::Pause - pausing audio stream 19:12:48.224 T:140275199145728 DEBUG: CDVDAudio::Flush - flush audio stream 19:12:48.224 T:140275199145728 DEBUG: CDVDAudio::Pause - pausing audio stream 19:12:48.550 T:140277129455680 DEBUG: CDVDMsgGeneralSynchronize - global timeout 19:12:48.551 T:140277129455680 DEBUG: ------ Window Deinit (Pointer.xml) ------ 19:12:48.554 T:140275190753024 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 19:12:48.554 T:140275199145728 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 19:12:48.556 T:140275645150976 DEBUG: CVideoPlayer - CDVDMsg::GENERAL_SYNCHRONIZE 19:12:48.557 T:140275645150976 DEBUG: DVDNAV_HOP_CHANNEL 19:12:48.557 T:140275645150976 DEBUG: ProcessBlock - Cell change: Title 2, Chapter 8 19:12:48.557 T:140275645150976 DEBUG: ProcessBlock - At position 52% inside the feature 19:12:48.557 T:140275645150976 DEBUG: DVDNAV_CELL_CHANGE 19:12:48.561 T:140276668229376 DEBUG: GetMovieId (removable://IKARIE_XB_1_344AB836/), query = select idMovie from movie where idFile=2 19:12:48.562 T:140276668229376 DEBUG: GetEpisodeId (removable://IKARIE_XB_1_344AB836/), query = select idEpisode from episode where idFile=2 19:12:48.563 T:140276668229376 DEBUG: GetMusicVideoId (removable://IKARIE_XB_1_344AB836/), query = select idMVideo from musicvideo where idFile=2 19:12:48.565 T:140276668229376 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc 19:12:48.566 T:140276668229376 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek 19:12:48.678 T:140275645150976 DEBUG: DVDNAV_NAV_PACKET (HOLDING) 19:12:48.779 T:140275645150976 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:2121160000 TO:2566520000 DIFF:445360000 19:12:48.788 T:140275190753024 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 19:12:48.788 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:12:48.796 T:140275199145728 DEBUG: Previous line repeats 5 times. 19:12:48.796 T:140275199145728 ERROR: ffmpeg[7F945D6A7700]: [ac3] frame sync error 19:12:48.796 T:140275199145728 ERROR: CVideoPlayerAudio::DecodeFrame - Decode Error. Skipping audio packet (-1094995529) 19:12:48.802 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:12:48.827 T:140275645150976 DEBUG: Previous line repeats 3 times. 19:12:48.827 T:140275645150976 DEBUG: CVideoPlayer::HandleMessages - player started 1 19:12:48.888 T:140275645150976 DEBUG: CVideoPlayer::HandleMessages - player started 2 19:12:48.888 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 3 19:12:48.888 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:12:48.889 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 0 19:12:48.889 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:12:48.889 T:140275645150976 DEBUG: VideoPlayer::Sync - Audio - pts: 2566440000.000000, cache: 293191.807325, totalcache: 550000.011921 19:12:48.889 T:140275645150976 DEBUG: VideoPlayer::Sync - Video - pts: 2566520000.000000, cache: 50000.000000, totalcache: 100000.000000 19:12:48.889 T:140275199145728 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(2566146808.192675) 19:12:48.889 T:140275199145728 DEBUG: CDVDAudio::Resume - resume audio stream 19:12:48.889 T:140276584675072 DEBUG: ActiveAE - start sync of audio stream 19:12:48.890 T:140275190753024 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(2566146808.192675) 19:12:48.890 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:12:49.004 T:140276584675072 DEBUG: Previous line repeats 1 times. 19:12:49.004 T:140276584675072 DEBUG: ActiveAE::SyncStream - average error of -0.716684, start adjusting 19:12:49.004 T:140276584675072 DEBUG: ActiveAE::SyncStream - average error -0.716684 below threshold of 30.000000 19:12:50.021 T:140275199145728 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-26548.421000, adjusted:-26548.421000 19:12:51.337 T:140277129455680 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 19:12:51.354 T:140277129455680 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 19:12:51.355 T:140277129455680 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 19:12:54.119 T:140275190753024 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000 19:12:54.683 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:12:54.934 T:140277129455680 DEBUG: ------ Window Init (Pointer.xml) ------ 19:12:54.947 T:140277129455680 DEBUG: ------ Window Init (VideoOSD.xml) ------ 19:12:54.951 T:140277129455680 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 19:12:54.952 T:140277129455680 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 19:12:57.532 T:140277129455680 DEBUG: ProcessMouse: trying mouse action leftclick 19:12:57.539 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 2 19:12:57.539 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:12:57.540 T:140275645150976 DEBUG: demuxer seek to: 3291644.000000 19:12:57.540 T:140275645150976 DEBUG: demuxer seek to: 3291644.000000, success 19:12:57.540 T:140275645150976 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 19:12:57.591 T:140275199145728 DEBUG: CDVDAudio::Pause - pausing audio stream 19:12:57.717 T:140275199145728 DEBUG: CDVDAudio::Flush - flush audio stream 19:12:57.717 T:140275199145728 DEBUG: CDVDAudio::Pause - pausing audio stream 19:12:57.717 T:140275199145728 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 19:12:57.717 T:140275190753024 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 19:12:57.719 T:140275645150976 DEBUG: CVideoPlayer - CDVDMsg::GENERAL_SYNCHRONIZE 19:12:57.721 T:140277129455680 DEBUG: ------ Window Deinit (Pointer.xml) ------ 19:12:57.722 T:140275645150976 DEBUG: DVDNAV_HOP_CHANNEL 19:12:57.723 T:140275645150976 DEBUG: ProcessBlock - Cell change: Title 2, Chapter 11 19:12:57.723 T:140275645150976 DEBUG: ProcessBlock - At position 66% inside the feature 19:12:57.723 T:140275645150976 DEBUG: DVDNAV_CELL_CHANGE 19:12:57.730 T:140276668229376 DEBUG: GetMovieId (removable://IKARIE_XB_1_344AB836/), query = select idMovie from movie where idFile=2 19:12:57.731 T:140276668229376 DEBUG: GetEpisodeId (removable://IKARIE_XB_1_344AB836/), query = select idEpisode from episode where idFile=2 19:12:57.731 T:140276668229376 DEBUG: GetMusicVideoId (removable://IKARIE_XB_1_344AB836/), query = select idMVideo from musicvideo where idFile=2 19:12:57.734 T:140276668229376 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc 19:12:57.734 T:140276668229376 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek 19:12:57.850 T:140275645150976 DEBUG: DVDNAV_NAV_PACKET (HOLDING) 19:12:57.952 T:140275645150976 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:2583240000 TO:3292400000 DIFF:709160000 19:12:57.957 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:12:57.974 T:140275199145728 ERROR: ffmpeg[7F945D6A7700]: [ac3] frame sync error 19:12:57.974 T:140275199145728 ERROR: CVideoPlayerAudio::DecodeFrame - Decode Error. Skipping audio packet (-1094995529) 19:12:57.984 T:140275190753024 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 19:12:57.984 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:12:57.993 T:140275645150976 DEBUG: Previous line repeats 2 times. 19:12:57.993 T:140275645150976 DEBUG: CVideoPlayer::HandleMessages - player started 1 19:12:57.994 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:12:58.066 T:140275645150976 DEBUG: Previous line repeats 7 times. 19:12:58.066 T:140275645150976 DEBUG: CVideoPlayer::HandleMessages - player started 2 19:12:58.067 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 3 19:12:58.068 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:12:58.068 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 0 19:12:58.068 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:12:58.068 T:140275645150976 DEBUG: VideoPlayer::Sync - Audio - pts: 3292392000.000000, cache: 300420.610825, totalcache: 550000.011921 19:12:58.068 T:140275645150976 DEBUG: VideoPlayer::Sync - Video - pts: 3292480000.000000, cache: 50000.000000, totalcache: 100000.000000 19:12:58.069 T:140275199145728 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(3292091579.389175) 19:12:58.069 T:140275199145728 DEBUG: CDVDAudio::Resume - resume audio stream 19:12:58.070 T:140275190753024 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(3292091579.389175) 19:12:58.070 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:12:58.071 T:140276584675072 DEBUG: ActiveAE - start sync of audio stream 19:12:58.085 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:12:58.227 T:140276584675072 DEBUG: ActiveAE::SyncStream - average error of -14.506127, start adjusting 19:12:58.227 T:140276584675072 DEBUG: ActiveAE::SyncStream - average error -0.506127 below threshold of 30.000000 19:12:58.931 T:140277129455680 DEBUG: ------ Window Init (Pointer.xml) ------ 19:12:59.083 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:12:59.372 T:140275199145728 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-25503.609802, adjusted:-25503.609802 19:13:00.593 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:13:03.064 T:140275190753024 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000 19:13:04.074 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:13:07.378 T:140277129455680 DEBUG: Previous line repeats 1 times. 19:13:07.379 T:140277129455680 DEBUG: ProcessMouse: trying mouse action leftclick 19:13:07.386 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 2 19:13:07.386 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:13:07.386 T:140275645150976 DEBUG: demuxer seek to: 3877774.000000 19:13:07.386 T:140275645150976 DEBUG: demuxer seek to: 3877774.000000, success 19:13:07.386 T:140275645150976 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 19:13:07.427 T:140275199145728 DEBUG: CDVDAudio::Pause - pausing audio stream 19:13:07.553 T:140275199145728 DEBUG: CDVDAudio::Flush - flush audio stream 19:13:07.553 T:140275199145728 DEBUG: CDVDAudio::Pause - pausing audio stream 19:13:07.553 T:140275199145728 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 19:13:07.553 T:140275190753024 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 19:13:07.557 T:140275645150976 DEBUG: CVideoPlayer - CDVDMsg::GENERAL_SYNCHRONIZE 19:13:07.557 T:140275645150976 DEBUG: DVDNAV_HOP_CHANNEL 19:13:07.557 T:140275645150976 DEBUG: ProcessBlock - Cell change: Title 2, Chapter 12 19:13:07.557 T:140275645150976 DEBUG: ProcessBlock - At position 78% inside the feature 19:13:07.557 T:140275645150976 DEBUG: DVDNAV_CELL_CHANGE 19:13:07.558 T:140277129455680 DEBUG: ------ Window Deinit (Pointer.xml) ------ 19:13:07.568 T:140276668229376 DEBUG: GetMovieId (removable://IKARIE_XB_1_344AB836/), query = select idMovie from movie where idFile=2 19:13:07.569 T:140276668229376 DEBUG: GetEpisodeId (removable://IKARIE_XB_1_344AB836/), query = select idEpisode from episode where idFile=2 19:13:07.570 T:140276668229376 DEBUG: GetMusicVideoId (removable://IKARIE_XB_1_344AB836/), query = select idMVideo from musicvideo where idFile=2 19:13:07.574 T:140276668229376 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc 19:13:07.574 T:140276668229376 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek 19:13:07.687 T:140275645150976 DEBUG: DVDNAV_NAV_PACKET (HOLDING) 19:13:07.789 T:140275645150976 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:3310160000 TO:3878640000 DIFF:568480000 19:13:07.796 T:140275199145728 ERROR: ffmpeg[7F945D6A7700]: [ac3] frame sync error 19:13:07.797 T:140275199145728 ERROR: CVideoPlayerAudio::DecodeFrame - Decode Error. Skipping audio packet (-1094995529) 19:13:07.800 T:140275190753024 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 19:13:07.800 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:13:07.806 T:140275645150976 DEBUG: Previous line repeats 3 times. 19:13:07.806 T:140275645150976 DEBUG: CVideoPlayer::HandleMessages - player started 1 19:13:07.806 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:13:07.809 T:140275645150976 DEBUG: Previous line repeats 1 times. 19:13:07.810 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:13:07.810 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:13:07.921 T:140275645150976 DEBUG: Previous line repeats 4 times. 19:13:07.921 T:140275645150976 DEBUG: CVideoPlayer::HandleMessages - player started 2 19:13:07.921 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 3 19:13:07.921 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:13:07.921 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 0 19:13:07.921 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:13:07.921 T:140275645150976 DEBUG: VideoPlayer::Sync - Audio - pts: 3878568000.000000, cache: 303597.937237, totalcache: 550000.011921 19:13:07.921 T:140275645150976 DEBUG: VideoPlayer::Sync - Video - pts: 3878720000.000000, cache: 50000.000000, totalcache: 100000.000000 19:13:07.921 T:140275190753024 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(3878264402.062763) 19:13:07.922 T:140275199145728 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(3878264402.062763) 19:13:07.922 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:13:07.922 T:140275199145728 DEBUG: CDVDAudio::Resume - resume audio stream 19:13:07.923 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:13:07.923 T:140276584675072 DEBUG: ActiveAE - start sync of audio stream 19:13:08.062 T:140276584675072 DEBUG: ActiveAE::SyncStream - average error of -23.928670, start adjusting 19:13:08.062 T:140276584675072 DEBUG: ActiveAE::SyncStream - average error -0.928670 below threshold of 30.000000 19:13:08.246 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:13:09.207 T:140275199145728 DEBUG: Previous line repeats 1 times. 19:13:09.207 T:140275199145728 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-27766.739263, adjusted:-27766.739263 19:13:09.660 T:140277129455680 DEBUG: ------ Window Init (Pointer.xml) ------ 19:13:09.784 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:13:12.995 T:140275190753024 DEBUG: Previous line repeats 1 times. 19:13:12.996 T:140275190753024 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000 19:13:14.125 T:140277129455680 DEBUG: ProcessMouse: trying mouse action leftclick 19:13:14.132 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 2 19:13:14.132 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:13:14.132 T:140275645150976 DEBUG: demuxer seek to: 543431.000000 19:13:14.132 T:140275645150976 DEBUG: demuxer seek to: 543431.000000, success 19:13:14.132 T:140275645150976 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 19:13:14.295 T:140275199145728 DEBUG: CDVDAudio::Pause - pausing audio stream 19:13:14.421 T:140275199145728 DEBUG: CDVDAudio::Flush - flush audio stream 19:13:14.421 T:140275199145728 DEBUG: CDVDAudio::Pause - pausing audio stream 19:13:14.421 T:140275199145728 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 19:13:14.421 T:140275190753024 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 19:13:14.423 T:140275645150976 DEBUG: CVideoPlayer - CDVDMsg::GENERAL_SYNCHRONIZE 19:13:14.423 T:140275645150976 DEBUG: DVDNAV_HOP_CHANNEL 19:13:14.423 T:140275645150976 DEBUG: ProcessBlock - Cell change: Title 2, Chapter 2 19:13:14.423 T:140275645150976 DEBUG: ProcessBlock - At position 11% inside the feature 19:13:14.423 T:140275645150976 DEBUG: DVDNAV_CELL_CHANGE 19:13:14.424 T:140277129455680 DEBUG: ------ Window Deinit (Pointer.xml) ------ 19:13:14.436 T:140276668229376 DEBUG: GetMovieId (removable://IKARIE_XB_1_344AB836/), query = select idMovie from movie where idFile=2 19:13:14.437 T:140276668229376 DEBUG: GetEpisodeId (removable://IKARIE_XB_1_344AB836/), query = select idEpisode from episode where idFile=2 19:13:14.438 T:140276668229376 DEBUG: GetMusicVideoId (removable://IKARIE_XB_1_344AB836/), query = select idMVideo from musicvideo where idFile=2 19:13:14.442 T:140276668229376 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc 19:13:14.442 T:140276668229376 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek 19:13:14.632 T:140275645150976 DEBUG: DVDNAV_NAV_PACKET (HOLDING) 19:13:14.733 T:140275645150976 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:3892960000 TO:544280000 DIFF:-3348680000 19:13:14.807 T:140275190753024 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 19:13:14.807 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:13:14.816 T:140275199145728 DEBUG: Previous line repeats 6 times. 19:13:14.816 T:140275199145728 ERROR: ffmpeg[7F945D6A7700]: [ac3] frame sync error 19:13:14.816 T:140275199145728 ERROR: CVideoPlayerAudio::DecodeFrame - Decode Error. Skipping audio packet (-1094995529) 19:13:14.818 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:13:14.892 T:140275645150976 DEBUG: Previous line repeats 3 times. 19:13:14.892 T:140275645150976 DEBUG: CVideoPlayer::HandleMessages - player started 1 19:13:14.892 T:140275645150976 DEBUG: CVideoPlayer::HandleMessages - player started 2 19:13:14.892 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 3 19:13:14.892 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:13:14.892 T:140275645150976 DEBUG: CVideoPlayer::SetCaching - caching state 0 19:13:14.892 T:140275645150976 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 19:13:14.893 T:140275645150976 DEBUG: VideoPlayer::Sync - Audio - pts: 95987917688.000000, cache: 302774.225746, totalcache: 550000.011921 19:13:14.893 T:140275645150976 DEBUG: VideoPlayer::Sync - Video - pts: 95988077688.000000, cache: 50000.000000, totalcache: 100000.000000 19:13:14.893 T:140275199145728 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(95987614913.774246) 19:13:14.893 T:140275199145728 DEBUG: CDVDAudio::Resume - resume audio stream 19:13:14.894 T:140275190753024 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(95987614913.774246) 19:13:14.894 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:13:14.897 T:140276584675072 DEBUG: ActiveAE - start sync of audio stream 19:13:14.900 T:140275190753024 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 19:13:15.056 T:140276584675072 DEBUG: ActiveAE::SyncStream - average error of 31.082492, start adjusting 19:13:15.056 T:140276584675072 DEBUG: ActiveAE::SyncStream - average error 0.082492 below threshold of 30.000000 19:13:15.195 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:13:16.072 T:140275199145728 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-25531.383373, adjusted:-25531.383373 19:13:16.961 T:140275645150976 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 19:13:17.207 T:140277129455680 DEBUG: ------ Window Init (Pointer.xml) ------ 19:13:17.428 T:140277129455680 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 19:13:17.445 T:140277129455680 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 19:13:17.445 T:140277129455680 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 19:13:17.457 T:140277129455680 DEBUG: ------ Window Init (VideoOSD.xml) ------ 19:13:17.460 T:140277129455680 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 19:13:17.460 T:140277129455680 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 19:13:18.773 T:140277129455680 DEBUG: ProcessMouse: trying mouse action leftclick 19:13:18.774 T:140277129455680 NOTICE: CVideoPlayer::CloseFile() 19:13:18.774 T:140277129455680 NOTICE: VideoPlayer: waiting for threads to exit 19:13:18.781 T:140275645150976 NOTICE: CVideoPlayer::OnExit() 19:13:18.781 T:140275645150976 NOTICE: Closing stream player 1 19:13:18.781 T:140275645150976 NOTICE: Waiting for audio thread to exit 19:13:18.787 T:140275199145728 NOTICE: thread end: CVideoPlayerAudio::OnExit() 19:13:18.788 T:140275645150976 NOTICE: Closing audio device 19:13:18.788 T:140275199145728 DEBUG: Thread VideoPlayerAudio 140275199145728 terminating 19:13:18.850 T:140275645150976 DEBUG: CDVDAudio::Flush - flush audio stream 19:13:18.850 T:140276584675072 DEBUG: CActiveAE::DiscardStream - audio stream deleted 19:13:18.851 T:140276584675072 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 19:13:18.851 T:140275645150976 DEBUG: Previous line repeats 2 times. 19:13:18.851 T:140275645150976 NOTICE: Deleting audio codec 19:13:18.851 T:140276576282368 INFO: CActiveAESink::OpenSink - initialize sink 19:13:18.851 T:140275645150976 NOTICE: Closing stream player 2 19:13:18.852 T:140275645150976 NOTICE: waiting for video thread to exit 19:13:18.860 T:140275190753024 NOTICE: thread end: video_thread 19:13:18.860 T:140275190753024 DEBUG: Thread VideoPlayerVideo 140275190753024 terminating 19:13:18.862 T:140275645150976 NOTICE: deleting video codec 19:13:18.862 T:140275645150976 NOTICE: CVDPAU::Release pre-cleanup 19:13:18.869 T:140275027642112 NOTICE: CMixer::OnExit: Output Thread terminated 19:13:18.869 T:140275027642112 DEBUG: Thread Vdpau Mixer 140275027642112 terminating 19:13:18.871 T:140275038111488 DEBUG: VDPAU::PreCleanup - released output surface 19:13:18.877 T:140275645150976 NOTICE: Closing stream player 3 19:13:18.878 T:140275645150976 DEBUG: OnPlayBackStopped: play state was 2, starting 0 19:13:18.878 T:140275645150976 DEBUG: Thread VideoPlayer 140275645150976 terminating 19:13:18.878 T:140277129455680 NOTICE: VideoPlayer: finished waiting 19:13:18.879 T:140277129455680 DEBUG: DeleteRenderer - deleting renderer 19:13:18.879 T:140277129455680 NOTICE: (VDPAU) Close 19:13:18.879 T:140277129455680 NOTICE: (VDPAU) FiniVDPAUOutput 19:13:18.885 T:140275038111488 NOTICE: VDPAU::COutput: vdpau gl interop finished 19:13:18.891 T:140276668229376 DEBUG: GetMovieId (removable://IKARIE_XB_1_344AB836/), query = select idMovie from movie where idFile=2 19:13:18.892 T:140276668229376 DEBUG: GetEpisodeId (removable://IKARIE_XB_1_344AB836/), query = select idEpisode from episode where idFile=2 19:13:18.894 T:140276668229376 DEBUG: GetMusicVideoId (removable://IKARIE_XB_1_344AB836/), query = select idMVideo from musicvideo where idFile=2 19:13:18.897 T:140276668229376 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 19:13:18.897 T:140276668229376 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 19:13:18.904 T:140275038111488 NOTICE: COutput::OnExit: Output Thread terminated 19:13:18.904 T:140275038111488 DEBUG: Thread Vdpau Output 140275038111488 terminating 19:13:18.938 T:140277129455680 DEBUG: CVDPAU::FiniVDPAUOutput destroying 0 video surfaces 19:13:18.938 T:140277129455680 NOTICE: VDPAU::Close - closing decoder context 19:13:18.952 T:140277129455680 DEBUG: LinuxRendererGL: Cleaning up GL resources 19:13:18.952 T:140277129455680 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 19:13:18.952 T:140277129455680 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 19:13:18.953 T:140277129455680 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 19:13:18.962 T:140277129455680 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 19:13:18.962 T:140277129455680 DEBUG: ------ Window Init (Home.xml) ------ 19:13:18.963 T:140277129455680 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing.. 19:13:18.963 T:140277129455680 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing.. 19:13:18.963 T:140275038111488 DEBUG: Thread JobWorker start, auto delete: true 19:13:18.963 T:140277129455680 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing.. 19:13:18.963 T:140277129455680 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing.. 19:13:18.963 T:140275645150976 DEBUG: Thread JobWorker start, auto delete: true 19:13:19.051 T:140276576282368 DEBUG: CActiveAESink::OpenSink - trying to open device PULSE:Default 19:13:19.058 T:140276576282368 DEBUG: PulseAudio: Context authorizing 19:13:19.060 T:140276576282368 DEBUG: PulseAudio: Context setting name 19:13:19.061 T:140276576282368 DEBUG: PulseAudio: Context ready 19:13:19.154 T:140276576282368 DEBUG: PulseAudio: Stream ready 19:13:19.154 T:140276576282368 NOTICE: PulseAudio: Opened device Default in pcm mode with Buffersize 150 ms 19:13:19.162 T:140276576282368 DEBUG: CActiveAESink::OpenSink - PULSE Initialized: 19:13:19.162 T:140276576282368 DEBUG: Output Device : Default 19:13:19.162 T:140276576282368 DEBUG: Sample Rate : 44100 19:13:19.162 T:140276576282368 DEBUG: Sample Format : AE_FMT_FLOAT 19:13:19.162 T:140276576282368 DEBUG: Channel Count : 2 19:13:19.162 T:140276576282368 DEBUG: Channel Layout: FL,FR 19:13:19.162 T:140276576282368 DEBUG: Frames : 2205 19:13:19.162 T:140276576282368 DEBUG: Frame Size : 8 19:13:19.166 T:140276584675072 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 19:13:19.239 T:140277129455680 DEBUG: Previous line repeats 1 times. 19:13:19.239 T:140277129455680 DEBUG: ------ Window Deinit (Pointer.xml) ------ 19:13:19.303 T:140277129455680 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 19:13:19.304 T:140277129455680 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 19:13:19.306 T:140277129455680 NOTICE: CVideoPlayer::CloseFile() 19:13:19.306 T:140277129455680 NOTICE: VideoPlayer: waiting for threads to exit 19:13:19.306 T:140277129455680 NOTICE: VideoPlayer: finished waiting 19:13:19.306 T:140277129455680 NOTICE: CVideoPlayer::CloseFile() 19:13:19.306 T:140277129455680 NOTICE: VideoPlayer: waiting for threads to exit 19:13:19.306 T:140277129455680 NOTICE: VideoPlayer: finished waiting 19:13:19.311 T:140277129455680 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 19:13:19.576 T:140277129455680 DEBUG: ------ Window Init (Pointer.xml) ------ 19:13:19.806 T:140275038111488 DEBUG: DoWork - Saving file state for video item removable://IKARIE_XB_1_344AB836/ 19:13:19.830 T:140276668229376 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 19:13:19.830 T:140276668229376 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 19:13:23.556 T:140277129455680 DEBUG: ProcessMouse: trying mouse action leftclick 19:13:23.556 T:140277129455680 DEBUG: Activating window ID: 10111 19:13:23.556 T:140277129455680 DEBUG: ------ Window Init (DialogButtonMenu.xml) ------ 19:13:23.556 T:140277129455680 INFO: Loading skin file: DialogButtonMenu.xml, load type: KEEP_IN_MEMORY 19:13:23.571 T:140277129455680 DEBUG: ------ Window Deinit (Pointer.xml) ------ 19:13:24.257 T:140277129455680 DEBUG: ------ Window Init (Pointer.xml) ------ 19:13:25.221 T:140277129455680 DEBUG: ProcessMouse: trying mouse action leftclick 19:13:25.227 T:140277129455680 DEBUG: ------ Window Deinit (Pointer.xml) ------ 19:13:25.238 T:140276668229376 DEBUG: CAnnouncementManager - Announcement: OnQuit from xbmc 19:13:25.238 T:140277129455680 NOTICE: Storing total System Uptime 19:13:25.238 T:140276668229376 DEBUG: GOT ANNOUNCEMENT, type: 8, from xbmc, message OnQuit 19:13:25.238 T:140277129455680 NOTICE: Saving settings 19:13:25.248 T:140277129455680 NOTICE: Saving skin settings 19:13:25.251 T:140277129455680 NOTICE: stop all 19:13:25.251 T:140275645150976 DEBUG: Thread JobWorker 140275645150976 terminating (autodelete) 19:13:25.252 T:140275038111488 DEBUG: Thread JobWorker 140275038111488 terminating (autodelete) 19:13:25.252 T:140277129455680 NOTICE: stop player 19:13:25.252 T:140277129455680 DEBUG: NetworkMessage - Signaling network services to stop 19:13:25.252 T:140277129455680 NOTICE: ES: Stopping event server 19:13:25.252 T:140277129455680 DEBUG: NetworkMessage - Waiting for network services to stop 19:13:25.252 T:140277129455680 NOTICE: stopping upnp 19:13:25.403 T:140277129455680 NOTICE: stopping zeroconf publishing 19:13:25.761 T:140275217319680 DEBUG: Thread TCPServer 140275217319680 terminating 19:13:25.762 T:140275225712384 NOTICE: ES: UDP Event server stopped 19:13:25.762 T:140277129455680 NOTICE: stop dvd detect media 19:13:25.763 T:140275225712384 DEBUG: Thread EventServer 140275225712384 terminating 19:13:25.763 T:140277127931648 DEBUG: Thread DetectDVDMedia 140277127931648 terminating 19:13:25.763 T:140276152104704 DEBUG: Thread PeripEventScanner 140276152104704 terminating 19:13:25.793 T:140276459857664 DEBUG: Thread PeripBusUSBUdev 140276459857664 terminating 19:13:26.061 T:140276476643072 DEBUG: Thread PeripBusAddon 140276476643072 terminating 19:13:26.062 T:140277129455680 NOTICE: clean cached files! 19:13:26.064 T:140277129455680 DEBUG: ADDON: Stopping service addons. 19:13:26.067 T:140276468250368 DEBUG: Thread PeripBusCEC 140276468250368 terminating 19:13:26.068 T:140276584675072 DEBUG: Thread ActiveAE 140276584675072 terminating 19:13:26.088 T:140276576282368 DEBUG: Thread AESink 140276576282368 terminating 19:13:26.313 T:140277129455680 NOTICE: closing down remote control service 19:13:26.313 T:140276559496960 DEBUG: Failed to connect to LIRC. Giving up. 19:13:26.313 T:140276559496960 DEBUG: Thread RemoteControl 140276559496960 terminating 19:13:26.313 T:140277129455680 INFO: LIRC SetEnabled: disabled 19:13:26.313 T:140277129455680 NOTICE: stopped 19:13:26.514 T:140277129455680 NOTICE: destroy 19:13:26.514 T:140277129455680 NOTICE: unload skin 19:13:26.514 T:140277129455680 INFO: Unloading old skin ... 19:13:26.516 T:140277129455680 DEBUG: ------ Window Deinit () ------ 19:13:26.516 T:140277129455680 DEBUG: ------ Window Deinit (Home.xml) ------ 19:13:26.521 T:140277129455680 DEBUG: ------ Window Deinit (DialogButtonMenu.xml) ------ 19:13:26.528 T:140277129455680 DEBUG: CloseBundle - Closed bundle 19:13:26.554 T:140277129455680 INFO: XRANDR: /usr/lib/x86_64-linux-gnu/kodi/kodi-xrandr --screen 0 --output DVI-D-0 --mode 0x24e 19:13:26.636 T:140277129455680 NOTICE: unload sections 19:13:26.645 T:140276668229376 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 19:13:26.645 T:140276668229376 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 19:13:26.651 T:140277129455680 NOTICE: special://profile/ is mapped to: special://masterprofile/ 19:13:26.651 T:140277129455680 DEBUG: object 0 --> 0 instances 19:13:26.651 T:140277129455680 DEBUG: object 1 --> 1 instances 19:13:26.652 T:140277129455680 DEBUG: object 2 --> 0 instances 19:13:26.652 T:140277129455680 DEBUG: object 3 --> 0 instances 19:13:26.652 T:140277129455680 DEBUG: object 4 --> 0 instances 19:13:26.652 T:140277129455680 DEBUG: object 5 --> 0 instances 19:13:26.652 T:140277129455680 DEBUG: object 6 --> 0 instances 19:13:26.652 T:140277129455680 DEBUG: object 7 --> 0 instances 19:13:26.652 T:140277129455680 DEBUG: object 8 --> 0 instances 19:13:26.652 T:140277129455680 DEBUG: object 9 --> 0 instances 19:13:26.652 T:140277129455680 DEBUG: PVRManager - destroyed 19:13:26.654 T:140277129455680 DEBUG: ActiveAE DSP - destroyed 19:13:26.654 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been uninstalled.' 19:13:26.654 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been uninstalled.' 19:13:26.654 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in resource.images.weathericons.default has been uninstalled.' 19:13:26.654 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.audioencoder has been uninstalled.' 19:13:26.654 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been uninstalled.' 19:13:26.655 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in kodi.audiodecoder has been uninstalled.' 19:13:26.655 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been uninstalled.' 19:13:26.655 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in kodi.peripheral has been uninstalled.' 19:13:26.655 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been uninstalled.' 19:13:26.655 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in kodi.guilib has been uninstalled.' 19:13:26.655 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.wma has been uninstalled.' 19:13:26.655 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in kodi.adsp has been uninstalled.' 19:13:26.655 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in game.controller.default has been uninstalled.' 19:13:26.655 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been uninstalled.' 19:13:26.656 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been uninstalled.' 19:13:26.656 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in kodi.game has been uninstalled.' 19:13:26.656 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in service.xbmc.versioncheck has been uninstalled.' 19:13:26.656 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been uninstalled.' 19:13:26.656 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in kodi.resource has been uninstalled.' 19:13:26.656 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been uninstalled.' 19:13:26.656 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been uninstalled.' 19:13:26.656 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been uninstalled.' 19:13:26.656 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in skin.estouchy has been uninstalled.' 19:13:26.657 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in kodi.inputstream has been uninstalled.' 19:13:26.657 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been uninstalled.' 19:13:26.657 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been uninstalled.' 19:13:26.657 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.aac has been uninstalled.' 19:13:26.657 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been uninstalled.' 19:13:26.657 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in resource.language.en_gb has been uninstalled.' 19:13:26.657 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been uninstalled.' 19:13:26.657 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been uninstalled.' 19:13:26.657 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been uninstalled.' 19:13:26.658 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been uninstalled.' 19:13:26.658 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.webinterface has been uninstalled.' 19:13:26.658 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been uninstalled.' 19:13:26.658 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.kodi has been uninstalled.' 19:13:26.658 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been uninstalled.' 19:13:26.658 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been uninstalled.' 19:13:26.658 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been uninstalled.' 19:13:26.658 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been uninstalled.' 19:13:26.658 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been uninstalled.' 19:13:26.658 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been uninstalled.' 19:13:26.659 T:140277129455680 DEBUG: ADDON: cpluff: 'Plug-in skin.estuary has been uninstalled.' 19:13:26.659 T:140277129455680 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x4e87870 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 19:13:26.659 T:140277129455680 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x4eaddf0 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 19:13:26.661 T:140276668229376 DEBUG: Thread Announce 140276668229376 terminating 19:13:26.661 T:140277129455680 NOTICE: application stopped... 19:13:26.685 T:140277129455680 DEBUG: SECTION:UnloadDll(libcurl.so.4)