11:04:13 T:1087845576 NOTICE: special://profile/ is mapped to: special://masterprofile/ 11:04:13 T:1087845576 NOTICE: ----------------------------------------------------------------------- 11:04:13 T:1087845576 NOTICE: Starting XBMC (13.2 Git:20140817-0f3db05). Platform: ARM Android 32-bit 11:04:13 T:1087845576 NOTICE: Using Debug XBMC x32 build, compiled Aug 17 2014 by GCC 4.8.0 for ARM Android 32-bit API level 14 11:04:13 T:1087845576 NOTICE: Running on Android 32-bit API level 17 (Android, 3.4.0-perf-g8fbf834 armv7l) 11:04:13 T:1087845576 NOTICE: Host CPU: ARMv7 Processor rev 0 (v7l), 4 cores available 11:04:13 T:1087845576 NOTICE: Product: bueller, Device: bueller, Board: MSM8960 - Manufacturer: Amazon, Brand: qcom, Model: AFTB, Hardware: qcom 11:04:13 T:1087845576 NOTICE: ARM Features: Neon enabled 11:04:13 T:1087845576 NOTICE: special://xbmc/ is mapped to: /data/data/org.xbmc.xbmc/cache/apk/assets 11:04:13 T:1087845576 NOTICE: special://xbmcbin/ is mapped to: /data/data/org.xbmc.xbmc/cache/apk/assets 11:04:13 T:1087845576 NOTICE: special://masterprofile/ is mapped to: /storage/emulated/0/Android/data/org.xbmc.xbmc/files/.xbmc/userdata 11:04:13 T:1087845576 NOTICE: special://home/ is mapped to: /storage/emulated/0/Android/data/org.xbmc.xbmc/files/.xbmc 11:04:13 T:1087845576 NOTICE: special://temp/ is mapped to: /storage/emulated/0/Android/data/org.xbmc.xbmc/files/.xbmc/temp 11:04:13 T:1087845576 NOTICE: The executable running is: /system/bin/app_process 11:04:13 T:1087845576 NOTICE: Local hostname: localhost 11:04:13 T:1087845576 NOTICE: Log File is located: /storage/emulated/0/Android/data/org.xbmc.xbmc/files/.xbmc/temp/xbmc.log 11:04:13 T:1087845576 NOTICE: ----------------------------------------------------------------------- 11:04:13 T:1087845576 NOTICE: load settings... 11:04:13 T:1087845576 DEBUG: static int CAndroidFeatures::GetVersion(): android.os.Build.VERSION 17 11:04:13 T:1087845576 DEBUG: CSettings: loaded settings definition from special://xbmc/system/settings/settings.xml 11:04:13 T:1087845576 DEBUG: CSettings: loaded settings definition from special://xbmc/system/settings/android.xml 11:04:13 T:1087845576 NOTICE: Found 1 Lists of Devices 11:04:13 T:1087845576 NOTICE: Enumerated AUDIOTRACK devices: 11:04:13 T:1087845576 NOTICE: Device 1 11:04:13 T:1087845576 NOTICE: m_deviceName : AudioTrack 11:04:13 T:1087845576 NOTICE: m_displayName : android 11:04:13 T:1087845576 NOTICE: m_displayNameExtra: audiotrack 11:04:13 T:1087845576 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 11:04:13 T:1087845576 NOTICE: m_channels : FL,FR 11:04:13 T:1087845576 NOTICE: m_sampleRates : 48000 11:04:13 T:1087845576 NOTICE: m_dataFormats : AE_FMT_S16LE,AE_FMT_AC3,AE_FMT_DTS 11:04:13 T:1087845576 NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml) 11:04:13 T:1087845576 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml) 11:04:13 T:1087845576 NOTICE: Default DVD Player: dvdplayer 11:04:13 T:1087845576 NOTICE: Default Video Player: dvdplayer 11:04:13 T:1087845576 NOTICE: Default Audio Player: paplayer 11:04:13 T:1087845576 NOTICE: Disabled debug logging due to GUI setting. Level 0. 11:04:13 T:1087845576 NOTICE: Log level changed to 0 11:04:13 T:1087845576 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 11:04:13 T:1087845576 DEBUG: CPlayerCoreConfig::: created player DVDPlayer for core 1 11:04:13 T:1087845576 DEBUG: CPlayerCoreConfig::: created player oldmplayercore for core 1 11:04:13 T:1087845576 DEBUG: CPlayerCoreConfig::: created player PAPlayer for core 3 11:04:13 T:1087845576 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules 11:04:13 T:1087845576 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtv 11:04:13 T:1087845576 DEBUG: CPlayerSelectionRule::Initialize: creating rule: hdhomerun/myth/mms/udp 11:04:13 T:1087845576 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout 11:04:13 T:1087845576 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp 11:04:13 T:1087845576 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp 11:04:13 T:1087845576 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams 11:04:13 T:1087845576 DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp 11:04:13 T:1087845576 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2 11:04:13 T:1087845576 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd 11:04:13 T:1087845576 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage 11:04:13 T:1087845576 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf 11:04:13 T:1087845576 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv 11:04:13 T:1087845576 DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio 11:04:13 T:1087845576 NOTICE: Loaded playercorefactory configuration 11:04:13 T:1087845576 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml. 11:04:13 T:1087845576 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping. 11:04:13 T:1087845576 INFO: creating subdirectories 11:04:13 T:1087845576 INFO: userdata folder: special://masterprofile/ 11:04:13 T:1087845576 INFO: recording folder: 11:04:13 T:1087845576 INFO: screenshots folder: 11:04:13 T:1087845576 INFO: load language info file: special://xbmc/language/German/langinfo.xml 11:04:13 T:1087845576 DEBUG: trying to set locale to de_DE.UTF-8 11:04:13 T:1087845576 INFO: global locale set to C 11:04:13 T:1087845576 INFO: load German language file, from path: special://xbmc/language/ 11:04:13 T:1087845576 DEBUG: POParser: loaded 3162 strings from file /data/data/org.xbmc.xbmc/cache/apk/assets/language/German/strings.po 11:04:13 T:1087845576 DEBUG: POParser: loaded 0 strings from file /data/data/org.xbmc.xbmc/cache/apk/assets/language/English/strings.po 11:04:13 T:1087845576 DEBUG: SECTION:LoadDLL(libavutil-52-arm.so) 11:04:13 T:1087845576 DEBUG: Loading: /data/app-lib/org.xbmc.xbmc-1/libavutil-52-arm.so 11:04:13 T:1087845576 DEBUG: SECTION:LoadDLL(libavcodec-54-arm.so) 11:04:13 T:1087845576 DEBUG: Loading: /data/app-lib/org.xbmc.xbmc-1/libavcodec-54-arm.so 11:04:13 T:1087845576 DEBUG: SECTION:LoadDLL(libavformat-54-arm.so) 11:04:13 T:1087845576 DEBUG: Loading: /data/app-lib/org.xbmc.xbmc-1/libavformat-54-arm.so 11:04:13 T:1458656968 NOTICE: Thread ActiveAE start, auto delete: false 11:04:13 T:1458620816 NOTICE: Thread AESink start, auto delete: false 11:04:13 T:1458620816 INFO: CActiveAESink::OpenSink - initialize sink 11:04:13 T:1458620816 DEBUG: CActiveAESink::OpenSink - trying to open device AUDIOTRACK:AudioTrack 11:04:13 T:1458620816 DEBUG: CActiveAESink::OpenSink - AUDIOTRACK Initialized: 11:04:13 T:1458620816 DEBUG: Output Device : android 11:04:13 T:1458620816 DEBUG: Sample Rate : 48000 11:04:13 T:1458620816 DEBUG: Sample Format : AE_FMT_S16NE 11:04:13 T:1458620816 DEBUG: Channel Count : 2 11:04:13 T:1458620816 DEBUG: Channel Layout: FL,FR 11:04:13 T:1458620816 DEBUG: Frames : 1536 11:04:13 T:1458620816 DEBUG: Frame Samples : 3072 11:04:13 T:1458620816 DEBUG: Frame Size : 4 11:04:13 T:1458656968 DEBUG: SECTION:LoadDLL(libswresample-0-arm.so) 11:04:13 T:1458656968 DEBUG: Loading: /data/app-lib/org.xbmc.xbmc-1/libswresample-0-arm.so 11:04:13 T:1087845576 NOTICE: Running database version Addons16 11:04:13 T:1087845576 DEBUG: SECTION:LoadDLL(libcpluff-arm.so) 11:04:13 T:1087845576 DEBUG: Loading: /data/app-lib/org.xbmc.xbmc-1/libcpluff-arm.so 11:04:13 T:1087845576 NOTICE: ADDONS: Using repository repository.xbmc.org 11:04:13 T:1087845576 NOTICE: ADDONS: Using repository repository.pvr-android.xbmc.org 11:04:13 T:1087845576 DEBUG: bool PERIPHERALS::CPeripherals::LoadMappings() - loaded node "Motorola Nyxboard Hybrid" 11:04:13 T:1087845576 DEBUG: bool PERIPHERALS::CPeripherals::LoadMappings() - loaded node "CEC Adapter" 11:04:13 T:1087845576 DEBUG: bool PERIPHERALS::CPeripherals::LoadMappings() - loaded node "Pulse-Eight CEC Adapter" 11:04:13 T:1087845576 DEBUG: bool PERIPHERALS::CPeripherals::LoadMappings() - loaded node "iMON HID device" 11:04:13 T:1087845576 DEBUG: PERIPHERALS::CPeripheralBusUSB::CPeripheralBusUSB(PERIPHERALS::CPeripherals*) - using libusb peripheral scanning 11:04:13 T:1087845576 DEBUG: SECTION:LoadDLL(libcec.so) 11:04:13 T:1087845576 DEBUG: Loading: /system/lib/libcec.so 11:04:13 T:1087845576 WARNING: Unable to resolve: libcec.so CECInitialise, reason: Symbol not found: 11:04:13 T:1087845576 ERROR: Unable to resolve exports from dll libcec.so 11:04:13 T:1087845576 ERROR: virtual void PERIPHERALS::CPeripherals::Initialise() - failed to initialise bus cec 11:04:13 T:1477833896 NOTICE: Thread PeripBusUSB start, auto delete: false 11:04:13 T:1087845576 NOTICE: virtual bool CWinSystemEGL::InitWindowSystem(): Using EGL Implementation: android 11:04:13 T:1087845576 DEBUG: bool CWinSystemEGL::CreateWindow(RESOLUTION_INFO&): Created surface of size 1920x1080 11:04:13 T:1087845576 NOTICE: Current resolution: 1920x1080 @ 60.00 - Full Screen 11:04:13 T:1087845576 NOTICE: Previous line repeats 1 times. 11:04:13 T:1087845576 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 60.000000 Hz 11:04:13 T:1087845576 NOTICE: Found (1920x1080@60.000000) at 16, setting to RES_DESKTOP at 16 11:04:13 T:1087845576 NOTICE: Checking resolution 16 11:04:13 T:1087845576 NOTICE: Current resolution: 1920x1080 @ 60.00 - Full Screen 11:04:13 T:1087845576 DEBUG: bool CWinSystemEGL::CreateWindow(RESOLUTION_INFO&): Created surface of size 1920x1080 11:04:13 T:1087845576 NOTICE: GL_VENDOR = Qualcomm 11:04:13 T:1087845576 NOTICE: GL_RENDERER = Adreno (TM) 320 11:04:13 T:1087845576 NOTICE: GL_VERSION = OpenGL ES 3.0 V@14.0 AU@04.02.02.073.175 PDAVID_AU_LINUX_ANDROID_JB_2.5.4.04.02.02.073.175+PATCH[ES]_msm8960_JB_2.5.4_CL3406509_release_ENGG (CL@3406509) 11:04:13 T:1087845576 NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 3.00 11:04:13 T:1087845576 DEBUG: GLES: Extension Support Test - GL_NVX_gpu_memory_info NO 11:04:13 T:1087845576 NOTICE: GL_EXTENSIONS = GL_AMD_compressed_ATC_texture GL_AMD_performance_monitor GL_AMD_program_binary_Z400 GL_EXT_debug_labelGL_EXT_debug_markerGL_EXT_robustness GL_EXT_texture_format_BGRA8888 GL_EXT_texture_type_2_10_10_10_REV GL_NV_fence GL_OES_compressed_ETC1_RGB8_texture GL_OES_depth_texture GL_OES_depth24 GL_OES_EGL_image GL_OES_EGL_image_external GL_OES_element_index_uint GL_OES_fbo_render_mipmap GL_OES_fragment_precision_high GL_OES_get_program_binary GL_OES_packed_depth_stencil GL_OES_depth_texture_cube_map GL_OES_rgb8_rgba8 GL_OES_standard_derivatives GL_OES_texture_3D GL_OES_texture_float GL_OES_texture_half_float GL_OES_texture_half_float_linear GL_OES_texture_npot GL_OES_vertex_half_float GL_OES_vertex_type_10_10_10_2 GL_OES_vertex_array_object GL_QCOM_alpha_test GL_QCOM_binning_control GL_QCOM_driver_control GL_QCOM_perfmon_global_mode GL_QCOM_extended_get GL_QCOM_extended_get2 GL_QCOM_tiled_rendering GL_QCOM_writeonly_rendering GL_EXT_sRGB GL_EXT_texture_filter_anisotropic GL_EXT_color_buffer_float GL_EXT_color_buffer_half_float 11:04:13 T:1087845576 DEBUG: GLES: Extension Support Test - GL_EXT_texture_format_BGRA8888 YES 11:04:13 T:1087845576 DEBUG: GLES: Extension Support Test - GL_IMG_texture_format_BGRA8888 NO 11:04:13 T:1087845576 DEBUG: GLES: Extension Support Test - GL_APPLE_texture_format_BGRA8888 NO 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GL: Pixel Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Fragment Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GUI Shader [guishader_frag_default.glsl]- Initialise successful : 0x585d9f88 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GL: Pixel Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Fragment Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GUI Shader [guishader_frag_texture.glsl]- Initialise successful : 0x5877dcd0 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GL: Pixel Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Fragment Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GUI Shader [guishader_frag_multi.glsl]- Initialise successful : 0x58748e88 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GL: Pixel Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Fragment Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GUI Shader [guishader_frag_fonts.glsl]- Initialise successful : 0x58798198 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GL: Pixel Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Fragment Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GUI Shader [guishader_frag_texture_noblend.glsl]- Initialise successful : 0x58769c60 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GL: Pixel Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Fragment Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GUI Shader [guishader_frag_multi_blendcolor.glsl]- Initialise successful : 0x5881c518 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GL: Pixel Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Fragment Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GUI Shader [guishader_frag_rgba.glsl]- Initialise successful : 0x587b7898 11:04:13 T:1087845576 DEBUG: GLES: Extension Support Test - GL_OES_EGL_image_external YES 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GL: Pixel Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Fragment Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GUI Shader [guishader_frag_rgba_oes.glsl]- Initialise successful : 0x5876fb80 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Vertex Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GL: Pixel Shader compilation log: 11:04:13 T:1087845576 DEBUG: GL: Fragment Shader compiled successfully 11:04:13 T:1087845576 DEBUG: GUI Shader [guishader_frag_rgba_blendcolor.glsl]- Initialise successful : 0x587da370 11:04:13 T:1087845576 NOTICE: Current resolution: 1920x1080 @ 60.00 - Full Screen 11:04:13 T:1087845576 DEBUG: CWinSystemEGL::CreateNewWindow: No need to create a new window 11:04:13 T:1087845576 INFO: GLES: Maximum texture width: 4096 11:04:13 T:1087845576 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec 11:04:13 T:1087845576 INFO: load default splash image: /data/data/org.xbmc.xbmc/cache/apk/assets/media/Splash.png 11:04:13 T:1087845576 DEBUG: SECTION:LoadDLL(libImageLib-arm.so) 11:04:13 T:1087845576 DEBUG: Loading: /data/app-lib/org.xbmc.xbmc-1/libImageLib-arm.so 11:04:13 T:1087845576 INFO: load keymapping 11:04:13 T:1087845576 INFO: Loading special://xbmc/system/keymaps/appcommand.xml 11:04:13 T:1087845576 INFO: Loading special://xbmc/system/keymaps/gamepad.xml 11:04:13 T:1087845576 INFO: Loading special://xbmc/system/keymaps/joystick.xml 11:04:13 T:1087845576 NOTICE: No Joystick name specified, loading default map 11:04:13 T:1087845576 NOTICE: Previous line repeats 35 times. 11:04:13 T:1087845576 INFO: Loading special://xbmc/system/keymaps/keyboard.xml 11:04:13 T:1087845576 INFO: Loading special://xbmc/system/keymaps/mouse.xml 11:04:13 T:1087845576 INFO: Loading special://xbmc/system/keymaps/remote.xml 11:04:13 T:1087845576 INFO: Loading special://xbmc/system/keymaps/touchscreen.xml 11:04:13 T:1087845576 INFO: GUI format 1920x1080, Display 1920x1080 @ 60.00 - Full Screen 11:04:13 T:1087845576 DEBUG: guilib: Fill viewport on change for solving rendering passes 11:04:13 T:1087845576 DEBUG: SECTION:LoadDLL(libcurl.so) 11:04:13 T:1087845576 DEBUG: Loading: /data/app-lib/org.xbmc.xbmc-1/libcurl.so 11:04:13 T:1087845576 NOTICE: Running database version Addons16 11:04:13 T:1087845576 DEBUG: void CDatabaseManager::Initialize(bool), updating databases... 11:04:13 T:1087845576 NOTICE: Running database version ViewModes6 11:04:13 T:1087845576 NOTICE: Running database version Textures13 11:04:13 T:1087845576 NOTICE: Running database version MyMusic46 11:04:13 T:1087845576 NOTICE: Running database version MyVideos78 11:04:13 T:1087845576 NOTICE: Running database version TV22 11:04:13 T:1087845576 NOTICE: Running database version Epg7 11:04:13 T:1087845576 DEBUG: void CDatabaseManager::Initialize(bool), updating databases... DONE 11:04:13 T:1087845576 INFO: DPMS: not supported on this platform 11:04:13 T:1087845576 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 11:04:13 T:1087845576 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 11:04:13 T:1087845576 INFO: Unloading old skin ... 11:04:13 T:1087845576 INFO: load skin from: /data/data/org.xbmc.xbmc/cache/apk/assets/addons/skin.confluence (version: 2.2.20) 11:04:13 T:1087845576 INFO: load fonts for skin... 11:04:13 T:1087845576 INFO: Loading fonts from /data/data/org.xbmc.xbmc/cache/apk/assets/addons/skin.confluence/720p/Font.xml 11:04:13 T:1087845576 DEBUG: POParser: loaded 148 strings from file /data/data/org.xbmc.xbmc/cache/apk/assets/addons/skin.confluence/language/German/strings.po 11:04:13 T:1087845576 DEBUG: POParser: loaded 0 strings from file /data/data/org.xbmc.xbmc/cache/apk/assets/addons/skin.confluence/language/English/strings.po 11:04:13 T:1087845576 INFO: Loading skin includes from /data/data/org.xbmc.xbmc/cache/apk/assets/addons/skin.confluence/720p/includes.xml 11:04:14 T:1087845576 INFO: load new skin... 11:04:14 T:1087845576 INFO: Loading user windows, path /data/data/org.xbmc.xbmc/cache/apk/assets/addons/skin.confluence/720p 11:04:14 T:1087845576 DEBUG: Load Skin XML: 25.91ms 11:04:14 T:1087845576 INFO: initialize new skin... 11:04:14 T:1087845576 DEBUG: guilib: Fill viewport on change for solving rendering passes 11:04:14 T:1087845576 INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT 11:04:14 T:1087845576 DEBUG: Load Pointer.xml: 2.01ms 11:04:14 T:1087845576 DEBUG: bool CTextureBundleXBT::OpenBundle() - Opened bundle /data/data/org.xbmc.xbmc/cache/apk/assets/addons/skin.confluence/media/Textures.xbt 11:04:14 T:1087845576 DEBUG: Alloc resources: 11.81ms (2.20 ms skin load) 11:04:14 T:1087845576 INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT 11:04:14 T:1087845576 DEBUG: Load DialogVolumeBar.xml: 5.31ms 11:04:14 T:1087845576 DEBUG: Alloc resources: 5.77ms (5.58 ms skin load) 11:04:14 T:1087845576 INFO: Loading skin file: DialogKaiToast.xml, load type: LOAD_ON_GUI_INIT 11:04:14 T:1087845576 DEBUG: Load DialogKaiToast.xml: 3.85ms 11:04:14 T:1087845576 DEBUG: Alloc resources: 4.09ms (4.09 ms skin load) 11:04:14 T:1087845576 INFO: Loading skin file: DialogMuteBug.xml, load type: LOAD_ON_GUI_INIT 11:04:14 T:1087845576 DEBUG: Load DialogMuteBug.xml: 1.16ms 11:04:14 T:1087845576 DEBUG: Alloc resources: 1.31ms (1.31 ms skin load) 11:04:14 T:1087845576 INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT 11:04:14 T:1087845576 DEBUG: Load DialogSeekBar.xml: 14.19ms 11:04:14 T:1087845576 DEBUG: Alloc resources: 15.38ms (14.34 ms skin load) 11:04:14 T:1087845576 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT 11:04:14 T:1087845576 DEBUG: Load DialogBusy.xml: 3.69ms 11:04:14 T:1087845576 DEBUG: Alloc resources: 3.91ms (3.88 ms skin load) 11:04:14 T:1087845576 INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT 11:04:14 T:1087845576 DEBUG: Load DialogExtendedProgressBar.xml: 4.15ms 11:04:14 T:1087845576 DEBUG: Alloc resources: 4.33ms (4.33 ms skin load) 11:04:14 T:1087845576 INFO: Loading /data/data/org.xbmc.xbmc/cache/apk/assets/addons/skin.confluence/sounds/sounds.xml 11:04:14 T:1087845576 INFO: skin loaded... 11:04:14 T:1087845576 DEBUG: JSONRPC: JSON schema type stereoscopicmode references an unknown type GUI.Stereoscopy.Mode 11:04:14 T:1087845576 WARNING: JSONRPC: Could not parse type "GUI.Property.Value" 11:04:14 T:1087845576 INFO: JSONRPC: Adding type "GUI.Property.Value" to list of incomplete definitions (waiting for "GUI.Stereoscopy.Mode") 11:04:14 T:1087845576 INFO: JSONRPC: Resolving incomplete types/methods referencing GUI.Stereoscopy.Mode 11:04:14 T:1087845576 DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting 11:04:14 T:1087845576 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList" 11:04:14 T:1087845576 INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting") 11:04:14 T:1087845576 INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting 11:04:14 T:1087845576 INFO: JSONRPC v6.14.3: Successfully initialized 11:04:14 T:1087845576 DEBUG: ADDON: Starting service addons. 11:04:14 T:1511167680 NOTICE: Thread LanguageInvoker start, auto delete: false 11:04:14 T:1511167680 INFO: initializing python engine. 11:04:14 T:1087845576 DEBUG: Activating window ID: 12999 11:04:14 T:1087845576 DEBUG: ------ Window Init (Startup.xml) ------ 11:04:14 T:1087845576 INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME 11:04:14 T:1509771960 NOTICE: Thread JobWorker start, auto delete: true 11:04:14 T:1087845576 DEBUG: Load Startup.xml: 7.72ms 11:04:14 T:1087845576 DEBUG: Alloc resources: 7.97ms (7.93 ms skin load) 11:04:14 T:1087845576 INFO: removing tempfiles 11:04:14 T:1509771960 NOTICE: PVRManager - starting up 11:04:14 T:1087845576 DEBUG: ADDON: Starting service addons. 11:04:14 T:1087845576 NOTICE: initialize done 11:04:14 T:1087845576 NOTICE: Running the application... 11:04:14 T:1087845576 DEBUG: Activating window ID: 10000 11:04:14 T:1509790208 NOTICE: Thread PVRManager start, auto delete: false 11:04:14 T:1511108944 NOTICE: Thread PVRClient start, auto delete: false 11:04:14 T:1511108944 DEBUG: int PVR::CPVRClients::RegisterClient(ADDON::AddonPtr) - registering add-on 'Tvheadend HTSP Client' 11:04:14 T:1511108944 DEBUG: PVR - ADDON_STATUS PVR::CPVRClient::Create(int) - creating PVR add-on instance 'Tvheadend HTSP Client' 11:04:14 T:1511108944 DEBUG: ADDON: Dll Initializing - Tvheadend HTSP Client 11:04:14 T:1511108944 DEBUG: SECTION:LoadDLL(/data/app-lib/org.xbmc.xbmc-1/libXBMC_Tvheadend.so) 11:04:14 T:1511108944 DEBUG: Loading: /data/app-lib/org.xbmc.xbmc-1/libXBMC_Tvheadend.so 11:04:14 T:1511108944 DEBUG: AddOnLog: Tvheadend HTSP Client: ADDON_Create - Creating Tvheadend PVR-Client 11:04:14 T:1511108944 DEBUG: CAddonCallbacksAddon - static bool ADDON::CAddonCallbacksAddon::GetAddonSetting(void*, const char*, void*) - add-on 'Tvheadend HTSP Client' requests setting 'host' 11:04:14 T:1511108944 DEBUG: CAddonCallbacksAddon - static bool ADDON::CAddonCallbacksAddon::GetAddonSetting(void*, const char*, void*) - add-on 'Tvheadend HTSP Client' requests setting 'user' 11:04:14 T:1511108944 DEBUG: CAddonCallbacksAddon - static bool ADDON::CAddonCallbacksAddon::GetAddonSetting(void*, const char*, void*) - add-on 'Tvheadend HTSP Client' requests setting 'pass' 11:04:14 T:1511108944 DEBUG: CAddonCallbacksAddon - static bool ADDON::CAddonCallbacksAddon::GetAddonSetting(void*, const char*, void*) - add-on 'Tvheadend HTSP Client' requests setting 'htsp_port' 11:04:14 T:1511108944 DEBUG: CAddonCallbacksAddon - static bool ADDON::CAddonCallbacksAddon::GetAddonSetting(void*, const char*, void*) - add-on 'Tvheadend HTSP Client' requests setting 'http_port' 11:04:14 T:1511108944 DEBUG: CAddonCallbacksAddon - static bool ADDON::CAddonCallbacksAddon::GetAddonSetting(void*, const char*, void*) - add-on 'Tvheadend HTSP Client' requests setting 'connect_timeout' 11:04:14 T:1511108944 DEBUG: CAddonCallbacksAddon - static bool ADDON::CAddonCallbacksAddon::GetAddonSetting(void*, const char*, void*) - add-on 'Tvheadend HTSP Client' requests setting 'response_timeout' 11:04:14 T:1511108944 DEBUG: CAddonCallbacksAddon - static bool ADDON::CAddonCallbacksAddon::GetAddonSetting(void*, const char*, void*) - add-on 'Tvheadend HTSP Client' requests setting 'transcode' 11:04:14 T:1511108944 DEBUG: CAddonCallbacksAddon - static bool ADDON::CAddonCallbacksAddon::GetAddonSetting(void*, const char*, void*) - add-on 'Tvheadend HTSP Client' requests setting 'audio_codec_name' 11:04:14 T:1511108944 DEBUG: CAddonCallbacksAddon - static bool ADDON::CAddonCallbacksAddon::GetAddonSetting(void*, const char*, void*) - add-on 'Tvheadend HTSP Client' requests setting 'video_codec_name' 11:04:14 T:1511108944 DEBUG: CAddonCallbacksAddon - static bool ADDON::CAddonCallbacksAddon::GetAddonSetting(void*, const char*, void*) - add-on 'Tvheadend HTSP Client' requests setting 'resolution' 11:04:14 T:1511108944 DEBUG: AddOnLog: Tvheadend HTSP Client: OpenSocket - connecting to '192.168.2.3', port '9982' 11:04:14 T:1511108944 DEBUG: AddOnLog: Tvheadend HTSP Client: OpenSocket - connected to '192.168.2.3', port '9982' 11:04:14 T:1511108944 NOTICE: AddOnLog: Tvheadend HTSP Client: CHTSPConnection - SendGreeting - connection opened to 'HTS Tvheadend ', protocol v10 11:04:14 T:1511108944 DEBUG: AddOnLog: Tvheadend HTSP Client: CHTSPConnection - Auth - authenticating as user 'admin' with a password 11:04:14 T:1087845576 INFO: GLES: Enabling VSYNC 11:04:14 T:1087845576 INFO: GLES: Selected vsync mode 10 11:04:14 T:1509790208 DEBUG: PVRManager - bool PVR::CPVRManager::Load() - active clients found. continue to start 11:04:14 T:1087845576 DEBUG: ------ Window Deinit (Startup.xml) ------ 11:04:14 T:1087845576 DEBUG: ------ Window Init (Home.xml) ------ 11:04:14 T:1087845576 INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY 11:04:14 T:1511167680 DEBUG: CPythonInvoker(0, /storage/emulated/0/Android/data/org.xbmc.xbmc/files/.xbmc/addons/service.xbmc.versioncheck/service.py): start processing 11:04:14 T:1511167680 NOTICE: -->Python Interpreter Initialized<-- 11:04:14 T:1511167680 DEBUG: CPythonInvoker(0, /storage/emulated/0/Android/data/org.xbmc.xbmc/files/.xbmc/addons/service.xbmc.versioncheck/service.py): the source file to load is "/storage/emulated/0/Android/data/org.xbmc.xbmc/files/.xbmc/addons/service.xbmc.versioncheck/service.py" 11:04:14 T:1511167680 DEBUG: CPythonInvoker(0, /storage/emulated/0/Android/data/org.xbmc.xbmc/files/.xbmc/addons/service.xbmc.versioncheck/service.py): setting the Python path to /storage/emulated/0/Android/data/org.xbmc.xbmc/files/.xbmc/addons/service.xbmc.versioncheck:/data/data/org.xbmc.xbmc/cache/apk/assets/addons/script.module.pil/lib:/data/data/org.xbmc.xbmc/cache/apk/assets/addons/xbmc.debug/:/:/data/app/org.xbmc.xbmc-1.apk/assets/python2.6/lib/python26.zip:/data/app/org.xbmc.xbmc-1.apk/assets/python2.6/lib/python2.6:/data/app/org.xbmc.xbmc-1.apk/assets/python2.6/lib/python2.6/plat-linux3:/data/app/org.xbmc.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-tk:/data/app/org.xbmc.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-old:/data/app/org.xbmc.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-dynload 11:04:14 T:1511167680 DEBUG: CPythonInvoker(0, /storage/emulated/0/Android/data/org.xbmc.xbmc/files/.xbmc/addons/service.xbmc.versioncheck/service.py): entering source directory /storage/emulated/0/Android/data/org.xbmc.xbmc/files/.xbmc/addons/service.xbmc.versioncheck 11:04:14 T:1511167680 DEBUG: CPythonInvoker(0, /storage/emulated/0/Android/data/org.xbmc.xbmc/files/.xbmc/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 11:04:14 T:1511167680 DEBUG: XBMC Version Check: Version 0.1.23 started 11:04:14 T:1087845576 DEBUG: Load Home.xml: 213.93ms 11:04:14 T:1087845576 DEBUG: Alloc resources: 226.68ms (214.11 ms skin load) 11:04:14 T:1509771960 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update 11:04:14 T:1511167680 INFO: CPythonInvoker(0, /storage/emulated/0/Android/data/org.xbmc.xbmc/files/.xbmc/addons/service.xbmc.versioncheck/service.py): script successfully run 11:04:14 T:1087845576 DEBUG: no profile autoexec.py (/storage/emulated/0/Android/data/org.xbmc.xbmc/files/.xbmc/userdata/autoexec.py) found, skipping 11:04:14 T:1087845576 DEBUG: void CNetwork::NetworkMessage(CNetwork::EMESSAGE, int) - Starting network services 11:04:14 T:1087845576 NOTICE: starting zeroconf publishing 11:04:14 T:1516372216 NOTICE: Thread ZerocconfEmbedded start, auto delete: false 11:04:14 T:1087845576 NOTICE: ES: Starting event server 11:04:14 T:1518218768 NOTICE: Thread JobWorker start, auto delete: true 11:04:14 T:1516372216 DEBUG: ZeroconfEmbedded - processing... 11:04:14 T:1087845576 INFO: JSONRPC Server: Successfully initialized 11:04:14 T:1515044176 NOTICE: Thread EventServer start, auto delete: false 11:04:14 T:1515044176 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777 11:04:14 T:1515044176 NOTICE: UDP: Listening on port 9777 11:04:14 T:1518218768 DEBUG: ZeroconfMDNS: identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:XBMC (localhost) port:9090 11:04:14 T:1087845576 NOTICE: Current resolution: 1920x1080 @ 60.00 - Full Screen 11:04:14 T:1087845576 DEBUG: CWinSystemEGL::CreateNewWindow: No need to create a new window 11:04:14 T:1087845576 NOTICE: GL_VENDOR = Qualcomm 11:04:14 T:1087845576 NOTICE: GL_RENDERER = Adreno (TM) 320 11:04:14 T:1087845576 NOTICE: GL_VERSION = OpenGL ES 3.0 V@14.0 AU@04.02.02.073.175 PDAVID_AU_LINUX_ANDROID_JB_2.5.4.04.02.02.073.175+PATCH[ES]_msm8960_JB_2.5.4_CL3406509_release_ENGG (CL@3406509) 11:04:14 T:1087845576 NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 3.00 11:04:14 T:1087845576 DEBUG: GLES: Extension Support Test - GL_NVX_gpu_memory_info NO 11:04:14 T:1087845576 NOTICE: GL_EXTENSIONS = GL_AMD_compressed_ATC_texture GL_AMD_performance_monitor GL_AMD_program_binary_Z400 GL_EXT_debug_labelGL_EXT_debug_markerGL_EXT_robustness GL_EXT_texture_format_BGRA8888 GL_EXT_texture_type_2_10_10_10_REV GL_NV_fence GL_OES_compressed_ETC1_RGB8_texture GL_OES_depth_texture GL_OES_depth24 GL_OES_EGL_image GL_OES_EGL_image_external GL_OES_element_index_uint GL_OES_fbo_render_mipmap GL_OES_fragment_precision_high GL_OES_get_program_binary GL_OES_packed_depth_stencil GL_OES_depth_texture_cube_map GL_OES_rgb8_rgba8 GL_OES_standard_derivatives GL_OES_texture_3D GL_OES_texture_float GL_OES_texture_half_float GL_OES_texture_half_float_linear GL_OES_texture_npot GL_OES_vertex_half_float GL_OES_vertex_type_10_10_10_2 GL_OES_vertex_array_object GL_QCOM_alpha_test GL_QCOM_binning_control GL_QCOM_driver_control GL_QCOM_perfmon_global_mode GL_QCOM_extended_get GL_QCOM_extended_get2 GL_QCOM_tiled_rendering GL_QCOM_writeonly_rendering GL_EXT_sRGB GL_EXT_texture_filter_anisotropic GL_EXT_color_buffer_float GL_EXT_color_buffer_half_float 11:04:14 T:1518218768 DEBUG: ZeroconfMDNS: identifier: servers.eventserver type: _xbmc-events._udp name:XBMC (localhost) port:9777 11:04:14 T:1515041600 NOTICE: Thread TCPServer start, auto delete: false 11:04:14 T:1515021608 NOTICE: Thread JobWorker start, auto delete: true 11:04:14 T:1087845576 DEBUG: GLES: Extension Support Test - GL_EXT_texture_format_BGRA8888 YES 11:04:14 T:1087845576 DEBUG: GLES: Extension Support Test - GL_IMG_texture_format_BGRA8888 NO 11:04:14 T:1087845576 DEBUG: GLES: Extension Support Test - GL_APPLE_texture_format_BGRA8888 NO 11:04:14 T:1087845576 DEBUG: GUI Shader - Tried to Initialise again. Was this intentional? 11:04:14 T:1087845576 DEBUG: ------ Window Init (DialogExtendedProgressBar.xml) ------ 11:04:14 T:1087845576 DEBUG: Window DialogExtendedProgressBar.xml was already loaded 11:04:14 T:1087845576 DEBUG: Alloc resources: 0.03m 11:04:14 T:1509790208 DEBUG: PVR - bool PVR::CPVRChannelGroups::Load() - loading all radio channel groups 11:04:14 T:1515065760 NOTICE: Thread AlarmClock start, auto delete: false 11:04:14 T:1509790208 INFO: PVRChannelGroupInternal - virtual int PVR::CPVRChannelGroupInternal::LoadFromDb(bool) - no channels in the database 11:04:14 T:1509790208 DEBUG: PVRChannelGroup - virtual bool PVR::CPVRChannelGroup::Load() - 0 channels loaded from the database for group 'Alle Radio-Kanäle' 11:04:14 T:1087845576 DEBUG: started alarm with name: checkatboot 11:04:14 T:1509790208 DEBUG: PVR - bool PVR::CPVRChannelGroups::LoadUserDefinedChannelGroups() - 0 user defined radio channel groups fetched from the database 11:04:14 T:1509790208 DEBUG: PVR - bool PVR::CPVRChannelGroups::LoadUserDefinedChannelGroups() - 0 new user defined radio channel groups fetched from clients 11:04:14 T:1509790208 DEBUG: PVR - bool PVR::CPVRChannelGroups::PersistAll() - persisting all changes in channel groups 11:04:14 T:1509790208 DEBUG: PVR - bool PVR::CPVRChannelGroups::Load() - 1 radio channel groups loaded 11:04:14 T:1509790208 DEBUG: PVR - bool PVR::CPVRChannelGroups::Load() - loading all TV channel groups 11:04:14 T:1087845576 DEBUG: started alarm with name: checkwhilerunning 11:04:14 T:1511167680 INFO: Python script stopped 11:04:14 T:1511167680 DEBUG: Thread LanguageInvoker 1511167680 terminating 11:04:14 T:1087845576 INFO: GLES: Enabling VSYNC 11:04:14 T:1087845576 INFO: GLES: Selected vsync mode 10 11:04:14 T:1509790208 DEBUG: PVRChannelGroup - virtual bool PVR::CPVRChannelGroup::Load() - 30 channels loaded from the database for group 'Alle TV-Kanäle' 11:04:14 T:1509771960 DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.* FROM (SELECT idAlbum FROM albumview ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum=recentalbums.idAlbum 11:04:14 T:1509771960 DEBUG: bool CMusicDatabase::GetRecentlyAddedAlbums(VECALBUMS&, unsigned int) query: select * from albumview where strAlbum != '' order by idAlbum desc limit 10 11:04:14 T:1509790208 DEBUG: PVR - bool PVR::CPVRDatabase::Get(PVR::CPVRChannelGroups&) - group 'Alle TV-Kanäle' loaded from the database 11:04:14 T:1509790208 DEBUG: PVR - bool PVR::CPVRChannelGroups::LoadUserDefinedChannelGroups() - 0 user defined TV channel groups fetched from the database 11:04:14 T:1509771960 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update 11:04:14 T:1509790208 DEBUG: PVR - bool PVR::CPVRChannelGroups::LoadUserDefinedChannelGroups() - 0 new user defined TV channel groups fetched from clients 11:04:14 T:1509790208 DEBUG: PVR - bool PVR::CPVRChannelGroups::PersistAll() - persisting all changes in channel groups 11:04:14 T:1509790208 DEBUG: PVR - bool PVR::CPVRChannelGroups::Load() - 1 TV channel groups loaded 11:04:14 T:1509790208 DEBUG: CPVRTimers - bool PVR::CPVRTimers::Update() - updating timers 11:04:14 T:1509790208 DEBUG: CPVRRecordings - void PVR::CPVRRecordings::Update() - updating recordings 11:04:14 T:1518203016 NOTICE: Thread PVRGUIInfo start, auto delete: false 11:04:14 T:1509790208 DEBUG: PVRManager - virtual void PVR::CPVRManager::Process() - entering main loop 11:04:14 T:1087845576 DEBUG: ------ Window Init (DialogExtendedProgressBar.xml) ------ 11:04:14 T:1087845576 DEBUG: Window DialogExtendedProgressBar.xml was already loaded 11:04:14 T:1087845576 DEBUG: Alloc resources: 0.00m 11:04:14 T:1509771960 DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 2 ms for 0 items query: select * from movieview ORDER BY dateAdded desc, idMovie desc LIMIT 10 11:04:14 T:1509771960 DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 1 ms for 0 items query: select * from episodeview ORDER BY dateAdded desc, idEpisode desc LIMIT 10 11:04:14 T:1509771960 DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 3 ms for 0 items query: select * from musicvideoview ORDER BY dateAdded desc, idMVideo desc LIMIT 10 11:04:14 T:1509771960 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update 11:04:15 T:1516372216 DEBUG: ZeroconfMDNS: XBMC (localhost)._xbmc-jsonrpc._tcp.local. registration removed 11:04:15 T:1516372216 DEBUG: ZeroconfMDNS: XBMC (localhost)._xbmc-events._udp.local. registration removed 11:04:17 T:1535741304 NOTICE: Thread EPGUpdater start, auto delete: false 11:04:17 T:1509790208 NOTICE: virtual void EPG::CEpgContainer::Start() - EPG thread started 11:04:17 T:1087845576 DEBUG: ------ Window Init (DialogExtendedProgressBar.xml) ------ 11:04:17 T:1087845576 DEBUG: Window DialogExtendedProgressBar.xml was already loaded 11:04:17 T:1087845576 DEBUG: Alloc resources: 0.03m 11:04:17 T:1509790208 DEBUG: CPVRTimers - bool PVR::CPVRTimers::Update() - updating timers 11:04:18 T:1087845576 DEBUG: ------ Window Deinit (DialogExtendedProgressBar.xml) ------ 11:04:18 T:1087845576 DEBUG: Keyboard: scancode: 0x15, sym: 0x0114, unicode: 0x0000, modifier: 0x0 11:04:18 T:1087845576 DEBUG: bool CApplication::OnKey(const CKey&): left (0xf082) pressed, action is Left 11:04:19 T:1087845576 DEBUG: Keyboard: scancode: 0x17, sym: 0x000d, unicode: 0x0000, modifier: 0x0 11:04:19 T:1087845576 DEBUG: bool CApplication::OnKey(const CKey&): return (0xf00d) pressed, action is Select 11:04:19 T:1087845576 DEBUG: Activating window ID: 10601 11:04:19 T:1087845576 DEBUG: ------ Window Deinit (Home.xml) ------ 11:04:19 T:1087845576 DEBUG: ------ Window Init (MyPVR.xml) ------ 11:04:19 T:1087845576 INFO: Loading skin file: MyPVR.xml, load type: LOAD_EVERY_TIME 11:04:19 T:1087845576 DEBUG: Load MyPVR.xml: 137.33ms 11:04:19 T:1087845576 DEBUG: Alloc resources: 143.65ms (137.60 ms skin load) 11:04:19 T:1087845576 DEBUG: CGUIMediaWindow::GetDirectory () 11:04:19 T:1087845576 DEBUG: ParentPath = [] 11:04:19 T:1087845576 DEBUG: CGUIWindowPVRCommon - virtual bool PVR::CGUIWindowPVRCommon::OnMessageFocus(CGUIMessage&) - focus set to window 'tv' 11:04:19 T:1087845576 DEBUG: CGUIWindowPVRChannels - virtual void PVR::CGUIWindowPVRChannels::UpdateData(bool) - update window 'tv'. set view to 11 11:04:19 T:1087845576 DEBUG: CGUIMediaWindow::GetDirectory (pvr://channels/tv/Alle TV-Kanäle/) 11:04:19 T:1087845576 DEBUG: ParentPath = [] 11:04:19 T:1518218768 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/tv/Alle TV-Kanäle) 11:04:19 T:1087845576 DEBUG: CGUIWindowPVRCommon - virtual bool PVR::CGUIWindowPVRCommon::OnMessageFocus(CGUIMessage&) - focus set to window 'tv' 11:04:19 T:1545286584 NOTICE: Thread JobWorker start, auto delete: true 11:04:20 T:1087845576 DEBUG: Keyboard: scancode: 0x16, sym: 0x0113, unicode: 0x0000, modifier: 0x0 11:04:20 T:1087845576 DEBUG: bool CApplication::OnKey(const CKey&): right (0xf083) pressed, action is Right 11:04:21 T:1087845576 DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0 11:04:21 T:1087845576 DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down 11:04:21 T:1087845576 DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0 11:04:21 T:1087845576 DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down 11:04:21 T:1087845576 DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0 11:04:21 T:1087845576 DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down 11:04:21 T:1087845576 DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0 11:04:21 T:1087845576 DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down 11:04:21 T:1087845576 DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0 11:04:21 T:1087845576 DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down 11:04:22 T:1087845576 DEBUG: Keyboard: scancode: 0x13, sym: 0x0111, unicode: 0x0000, modifier: 0x0 11:04:22 T:1087845576 DEBUG: bool CApplication::OnKey(const CKey&): up (0xf080) pressed, action is Up 11:04:22 T:1087845576 DEBUG: Keyboard: scancode: 0x17, sym: 0x000d, unicode: 0x0000, modifier: 0x0 11:04:22 T:1087845576 DEBUG: bool CApplication::OnKey(const CKey&): return (0xf00d) pressed, action is Select 11:04:22 T:1087845576 DEBUG: CPlayerCoreFactory::GetPlayers(pvr://channels/tv/Alle TV-Kanäle/4.pvr) 11:04:22 T:1087845576 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 11:04:22 T:1087845576 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 11:04:22 T:1087845576 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv 11:04:22 T:1087845576 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp 11:04:22 T:1087845576 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 11:04:22 T:1087845576 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 11:04:22 T:1087845576 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 11:04:22 T:1087845576 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 11:04:22 T:1087845576 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 11:04:22 T:1087845576 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 11:04:22 T:1087845576 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 11:04:22 T:1087845576 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 11:04:22 T:1087845576 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 11:04:22 T:1087845576 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 11:04:22 T:1087845576 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1) 11:04:22 T:1087845576 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 11:04:22 T:1087845576 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 11:04:22 T:1087845576 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1) 11:04:22 T:1087845576 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 11:04:22 T:1087845576 NOTICE: DVDPlayer: Opening: pvr://channels/tv/Alle TV-Kanäle/4.pvr 11:04:22 T:1087845576 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 11:04:22 T:1087845576 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec 11:04:22 T:1087845576 DEBUG: LinuxRendererGL: Cleaning up GL resources 11:04:22 T:1087845576 DEBUG: SECTION:LoadDLL(libswscale-2-arm.so) 11:04:22 T:1087845576 DEBUG: Loading: /data/app-lib/org.xbmc.xbmc-1/libswscale-2-arm.so 11:04:22 T:1524670280 NOTICE: Thread DVDPlayer start, auto delete: false 11:04:22 T:1524670280 NOTICE: Creating InputStream 11:04:22 T:1524670280 DEBUG: PVRManager - bool PVR::CPVRManager::OpenLiveStream(const CFileItem&) - opening live stream on channel 'SAT.1' 11:04:22 T:1524670280 DEBUG: opening live stream for channel 'SAT.1' 11:04:22 T:1524670280 INFO: AddOnLog: Tvheadend HTSP Client: SendSubscribe - subscribe to channel '9', subscription 1 11:04:22 T:1524670280 DEBUG: AddOnLog: Tvheadend HTSP Client: SendSubscribe - new subscription for channel 9 (1) 11:04:22 T:1524670280 DEBUG: PVRFile - virtual bool XFILE::CPVRFile::Open(const CURL&) - playback has started on filename pvr://channels/tv/Alle TV-Kanäle/4.pvr 11:04:22 T:1524670280 DEBUG: CDVDInputStreamPVRManager::Open - stream opened: pvr://channels/tv/Alle TV-Kanäle/4.pvr 11:04:22 T:1524670280 NOTICE: Creating Demuxer 11:04:23 T:1087845576 DEBUG: ------ Window Init (DialogBusy.xml) ------ 11:04:23 T:1087845576 DEBUG: Window DialogBusy.xml was already loaded 11:04:23 T:1087845576 DEBUG: Alloc resources: 0.00m 11:04:23 T:1524670280 ERROR: SelectionStream& CSelectionStreams::Get(StreamType, int) - failed to get stream 11:04:23 T:1524670280 DEBUG: CDVDPlayer::SetCaching - caching state 2 11:04:23 T:1524670280 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 11:04:23 T:1524670280 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 11:04:23 T:1524670280 DEBUG: virtual void CApplication::OnPlayBackStarted() : play state was 1, starting 1 11:04:23 T:1087845576 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 11:04:23 T:1087845576 DEBUG: Window DialogSeekBar.xml was already loaded 11:04:23 T:1087845576 DEBUG: Alloc resources: 0.03m 11:04:23 T:1087845576 DEBUG: PlayBackRet CApplication::PlayFile(const CFileItem&, bool) : OpenFile succeed, play state 2 11:04:23 T:1087845576 DEBUG: virtual void CApplication::OnPlayBackStarted() : play state was 2, starting 0 11:04:23 T:1087845576 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 11:04:23 T:1087845576 DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/Alle TV-Kanäle/4.pvr) 11:04:23 T:1087845576 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 11:04:23 T:1087845576 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 11:04:23 T:1511939368 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 1, type: TELETEXT, codec: 94215 11:04:23 T:1511939368 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 2, type: MPEG2VIDEO, codec: 2 11:04:23 T:1511939368 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 3, type: MPEG2AUDIO, codec: 86016 11:04:23 T:1511939368 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 4, type: AC3, codec: 86019 11:04:23 T:1511939368 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 5, type: TEXTSUB, codec: 94210 11:04:23 T:1511939368 INFO: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - subscription started on adapter PCTV 460e, mux BetaDigital: 12,544,750 kHz Horizontal (No satconf), network BetaDigital, provider DIGITAL+, service SAT.1 11:04:23 T:1524670280 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 0:2 with codec_id 2 11:04:23 T:1524670280 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 1:1 with codec_id 94215 11:04:23 T:1524670280 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 2:3 with codec_id 86016 11:04:23 T:1524670280 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 3:4 with codec_id 86019 11:04:23 T:1524670280 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 4:5 with codec_id 94210 11:04:23 T:1524670280 NOTICE: Opening video stream: 0 source: 256 11:04:23 T:1524670280 NOTICE: Creating video codec with codec id: 2 11:04:23 T:1524670280 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no AMCodec:yes MediaCodec:yes OpenMax:no libstagefright:yes VDPAU:no VAAPI:no 11:04:23 T:1524670280 INFO: MediaCodec Video Decoder... 11:04:23 T:1524670280 DEBUG: FactoryCodec - Video: mediacodec - Opening 11:04:23 T:1524670280 DEBUG: CDVDVideoCodecAndroidMediaCodec::Open m_codecname(OMX.qcom.video.decoder.mpeg2), colorFormat(2141391875) 11:04:23 T:1524670280 DEBUG: CDVDVideoCodecAndroidMediaCodec::Open m_codecname(OMX.qcom.video.decoder.mpeg2), colorFormat(19) 11:04:23 T:1524670280 DEBUG: FactoryCodec - Video: amc-mpeg2 - Failed 11:04:23 T:1524670280 DEBUG: FactoryCodec - Video: stf-xxxx - Opening 11:04:23 T:1524670280 DEBUG: CDVDVideoCodecStageFright::virtual bool CDVDVideoCodecStageFright::Open(CDVDStreamInfo&, CDVDCodecOptions&) - trying to open, codec(2), profile(-99), level(-99) 11:04:23 T:1524670280 DEBUG: SECTION:LoadDLL(libXBMCvcodec_stagefrightICS-arm.so) 11:04:23 T:1524670280 DEBUG: Loading: /data/app-lib/org.xbmc.xbmc-1/libXBMCvcodec_stagefrightICS-arm.so 11:04:23 T:1524670280 DEBUG: CStageFrightVideo::bool CStageFrightVideo::Open(CDVDStreamInfo&) - component: OMX.qcom.video.decoder.mpeg2 11:04:23 T:1524670280 DEBUG: FactoryCodec - Video: stf-mpeg2 - Opened 11:04:23 T:1528989464 NOTICE: Thread CStageFrightDecodeThread start, auto delete: true 11:04:23 T:1524670280 NOTICE: Creating video thread 11:04:23 T:1528989720 NOTICE: Thread DVDPlayerVideo start, auto delete: false 11:04:23 T:1528989720 NOTICE: running thread: video_thread 11:04:23 T:1524670280 NOTICE: Opening audio stream: 3 source: 256 11:04:23 T:1524670280 NOTICE: Finding audio codec for: 86019 11:04:23 T:1528989720 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 11:04:23 T:1524670280 DEBUG: FactoryCodec - Audio: passthrough - Opening 11:04:23 T:1524670280 DEBUG: FactoryCodec - Audio: passthrough - Failed 11:04:23 T:1524670280 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 11:04:23 T:1524670280 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 11:04:23 T:1524670280 NOTICE: Creating audio thread 11:04:23 T:1528981856 NOTICE: Thread DVDPlayerAudio start, auto delete: false 11:04:23 T:1528981856 NOTICE: running thread: CDVDPlayerAudio::Process() 11:04:23 T:1524670280 NOTICE: Opening Subtitle stream: 4 source: 256 11:04:23 T:1524670280 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opening 11:04:23 T:1524670280 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opened 11:04:23 T:1524670280 NOTICE: Opening teletext stream: 1 source: 256 11:04:23 T:1524670280 NOTICE: Creating teletext data thread 11:04:23 T:1528981920 NOTICE: Thread DVDTeletextData start, auto delete: false 11:04:23 T:1528981920 NOTICE: running thread: CDVDTeletextData 11:04:23 T:1528989720 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(32773806666.000000, 1) 11:04:23 T:1528989720 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback 11:04:23 T:1528989720 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available 11:04:23 T:1524670280 DEBUG: void CDVDDemuxPVRClient::ParsePacket(DemuxPacket*) - {2} iChannels changed from 0 to 2 11:04:23 T:1524670280 DEBUG: void CDVDDemuxPVRClient::ParsePacket(DemuxPacket*) - {2} iSampleRate changed from 0 to 48000 11:04:23 T:1524670280 DEBUG: void CDVDDemuxPVRClient::ParsePacket(DemuxPacket*) - {3} iChannels changed from 0 to 2 11:04:23 T:1524670280 DEBUG: void CDVDDemuxPVRClient::ParsePacket(DemuxPacket*) - {3} iSampleRate changed from 0 to 48000 11:04:23 T:1524670280 NOTICE: Opening audio stream: 3 source: 256 11:04:23 T:1524670280 NOTICE: Finding audio codec for: 86019 11:04:23 T:1524670280 DEBUG: FactoryCodec - Audio: passthrough - Opening 11:04:23 T:1524670280 DEBUG: FactoryCodec - Audio: passthrough - Failed 11:04:23 T:1524670280 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 11:04:23 T:1524670280 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 11:04:23 T:1528981856 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(32773700333.000000, 0) 11:04:23 T:1528981856 NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, no pass-through) 11:04:23 T:1528981856 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback 11:04:23 T:1524670280 NOTICE: Opening video stream: 0 source: 256 11:04:23 T:1524670280 NOTICE: Creating video codec with codec id: 2 11:04:23 T:1524670280 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no AMCodec:yes MediaCodec:yes OpenMax:no libstagefright:yes VDPAU:no VAAPI:no 11:04:23 T:1524670280 INFO: MediaCodec Video Decoder... 11:04:23 T:1524670280 DEBUG: FactoryCodec - Video: mediacodec - Opening 11:04:23 T:1524670280 DEBUG: CDVDVideoCodecAndroidMediaCodec::Open m_codecname(OMX.qcom.video.decoder.mpeg2), colorFormat(2141391875) 11:04:23 T:1524670280 DEBUG: CDVDVideoCodecAndroidMediaCodec::Open m_codecname(OMX.qcom.video.decoder.mpeg2), colorFormat(19) 11:04:23 T:1458620816 INFO: CActiveAESink::OpenSink - initialize sink 11:04:23 T:1524670280 DEBUG: FactoryCodec - Video: amc-mpeg2 - Failed 11:04:23 T:1524670280 DEBUG: FactoryCodec - Video: stf-xxxx - Opening 11:04:23 T:1524670280 DEBUG: CDVDVideoCodecStageFright::virtual bool CDVDVideoCodecStageFright::Open(CDVDStreamInfo&, CDVDCodecOptions&) - trying to open, codec(2), profile(-99), level(-99) 11:04:23 T:1458620816 DEBUG: CActiveAESink::OpenSink - trying to open device AUDIOTRACK:AudioTrack 11:04:23 T:1458620816 DEBUG: CActiveAESink::OpenSink - AUDIOTRACK Initialized: 11:04:23 T:1458620816 DEBUG: Output Device : android 11:04:23 T:1458620816 DEBUG: Sample Rate : 48000 11:04:23 T:1458620816 DEBUG: Sample Format : AE_FMT_S16NE 11:04:23 T:1458620816 DEBUG: Channel Count : 2 11:04:23 T:1458620816 DEBUG: Channel Layout: FL,FR 11:04:23 T:1458620816 DEBUG: Frames : 1536 11:04:23 T:1458620816 DEBUG: Frame Samples : 3072 11:04:23 T:1458620816 DEBUG: Frame Size : 4 11:04:23 T:1458656968 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 11:04:23 T:1524670280 DEBUG: CStageFrightVideo::bool CStageFrightVideo::Open(CDVDStreamInfo&) - component: OMX.qcom.video.decoder.mpeg2 11:04:23 T:1524670280 DEBUG: FactoryCodec - Video: stf-mpeg2 - Opened 11:04:23 T:1458657752 NOTICE: Thread CStageFrightDecodeThread start, auto delete: true 11:04:23 T:1524670280 DEBUG: CDVDPlayer::HandleMessages - player started 1 11:04:23 T:1528989464 DEBUG: Thread CStageFrightDecodeThread 1528989464 terminating (autodelete) 11:04:23 T:1528989720 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available 11:04:24 T:1528989720 NOTICE: fps: 25.000000, pwidth: 720, pheight: 576, dwidth: 720, dheight: 576 11:04:24 T:1528989720 DEBUG: int CDVDPlayerVideo::OutputPicture(const DVDVideoPicture*, double) - change configuration. 720x576. framerate: 25.00. format: EGLIMG 11:04:24 T:1528989720 NOTICE: Display resolution DESKTOP : 1920x1080 @ 60.00 - Full Screen (16) 11:04:24 T:1528989720 DEBUG: CXBMCRenderManager::Configure - 2 11:04:24 T:1087845576 NOTICE: Using GL_TEXTURE_2D 11:04:24 T:1087845576 DEBUG: GL: Requested render method: 0 11:04:24 T:1087845576 NOTICE: GL: Using EGL Image render method 11:04:24 T:1087845576 NOTICE: GL: NPOT texture support detected 11:04:24 T:1087845576 DEBUG: CLinuxRendererGLES: Reorder drawpoints due to method change from 1 to 512 11:04:24 T:1524670280 DEBUG: CDVDPlayer::HandleMessages - player started 2 11:04:24 T:1087845576 INFO: Python, unloading python shared library because no scripts are running anymore 11:04:24 T:1087845576 DEBUG: void XBPython::UnloadExtensionLibs(), clearing python extension libraries 11:04:25 T:1524670280 DEBUG: set caching from pvr to done. audio (1) = 18. video (1) = 13 11:04:25 T:1524670280 DEBUG: CDVDPlayer::SetCaching - caching state 0 11:04:25 T:1528981856 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:32773807154.280998, should be:32773694666.331226, error:-112487.949772 11:04:25 T:1524670280 ERROR: SelectionStream& CSelectionStreams::Get(StreamType, int) - failed to get stream 11:04:25 T:1087845576 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 11:04:30 T:1528989720 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000 11:04:31 T:1087845576 DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0 11:04:31 T:1087845576 DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down 11:04:35 T:1087845576 DEBUG: Keyboard: scancode: 0x17, sym: 0x000d, unicode: 0x0000, modifier: 0x0 11:04:35 T:1087845576 DEBUG: bool CApplication::OnKey(const CKey&): return (0xf00d) pressed, action is Select 11:04:35 T:1087845576 DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/Alle TV-Kanäle/5.pvr) 11:04:35 T:1528981856 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 11:04:35 T:1528989720 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 11:04:35 T:1524670280 DEBUG: CDVDPlayer::SetCaching - caching state 3 11:04:35 T:1524670280 DEBUG: PVRManager - bool PVR::CPVRManager::PerformChannelSwitch(const PVR::CPVRChannel&, bool) - switching to channel 'RTL2' 11:04:35 T:1524670280 DEBUG: PVR - void PVR::CPVRClients::SaveCurrentChannelSettings() - persisting custom channel settings for channel 'SAT.1' 11:04:35 T:1524670280 INFO: AddOnLog: Tvheadend HTSP Client: SwitchChannel - changing to channel 'RTL2' 11:04:35 T:1524670280 INFO: AddOnLog: Tvheadend HTSP Client: SendUnsubscribe - unsubscribe from subscription 1 11:04:35 T:1524670280 INFO: AddOnLog: Tvheadend HTSP Client: SendSubscribe - subscribe to channel '8', subscription 2 11:04:35 T:1524670280 DEBUG: AddOnLog: Tvheadend HTSP Client: SendSubscribe - new subscription for channel 9 (2) 11:04:35 T:1524670280 NOTICE: PVRManager - bool PVR::CPVRManager::PerformChannelSwitch(const PVR::CPVRChannel&, bool) - switched to channel 'RTL2' 11:04:35 T:1509790208 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 11:04:35 T:1524670280 NOTICE: Creating Demuxer 11:04:35 T:1509790208 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 11:04:35 T:1509790208 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 11:04:35 T:1509790208 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 11:04:36 T:1524670280 NOTICE: Closing video stream 11:04:36 T:1524670280 DEBUG: CDVDPlayer::SetCaching - caching state 0 11:04:36 T:1524670280 NOTICE: waiting for video thread to exit 11:04:36 T:1528989720 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true 11:04:36 T:1528989720 NOTICE: thread end: video_thread 11:04:36 T:1524670280 NOTICE: deleting video codec 11:04:36 T:1528989720 DEBUG: Thread DVDPlayerVideo 1528989720 terminating 11:04:36 T:1458657752 DEBUG: Thread CStageFrightDecodeThread 1458657752 terminating (autodelete) 11:04:36 T:1524670280 NOTICE: Closing audio stream 11:04:36 T:1524670280 NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty 11:04:36 T:1528981856 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 11:04:36 T:1524670280 NOTICE: Waiting for audio thread to exit 11:04:36 T:1528981856 DEBUG: CDVDPlayerAudio::Process - Abort received, exiting thread 11:04:36 T:1528981856 NOTICE: thread end: CDVDPlayerAudio::OnExit() 11:04:36 T:1528981856 DEBUG: Thread DVDPlayerAudio 1528981856 terminating 11:04:36 T:1524670280 NOTICE: Closing audio device 11:04:36 T:1524670280 NOTICE: Deleting audio codec 11:04:36 T:1458656968 DEBUG: CActiveAE::DiscardStream - audio stream deleted 11:04:36 T:1524670280 ERROR: SelectionStream& CSelectionStreams::Get(StreamType, int) - failed to get stream 11:04:36 T:1458656968 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 11:04:36 T:1524670280 NOTICE: Closing subtitle stream 11:04:36 T:1524670280 NOTICE: Closing teletext stream 11:04:36 T:1524670280 NOTICE: CDVDMessageQueue(teletext)::WaitUntilEmpty 11:04:36 T:1458656968 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 11:04:36 T:1524670280 NOTICE: waiting for teletext data thread to exit 11:04:36 T:1528981920 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true (-1) 11:04:36 T:1528981920 NOTICE: thread end: data_thread 11:04:36 T:1528981920 DEBUG: Thread DVDTeletextData 1528981920 terminating 11:04:36 T:1524670280 DEBUG: CDVDPlayer::SetCaching - caching state 2 11:04:36 T:1524670280 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 11:04:36 T:1524670280 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 11:04:36 T:1087845576 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 11:04:36 T:1087845576 DEBUG: Window DialogSeekBar.xml was already loaded 11:04:36 T:1087845576 DEBUG: Alloc resources: 0.43m 11:04:36 T:1511939368 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 1, type: MPEG2VIDEO, codec: 2 11:04:36 T:1511939368 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 2, type: MPEG2AUDIO, codec: 86016 11:04:36 T:1511939368 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 3, type: TELETEXT, codec: 94215 11:04:36 T:1511939368 INFO: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - subscription started on adapter PCTV 460e, mux Telewizja Polska SA: 12,187,500 kHz Horizontal (No satconf), network Telewizja Polska SA, provider RTL World, service RTL2 11:04:36 T:1458620816 INFO: CActiveAESink::OpenSink - initialize sink 11:04:36 T:1524670280 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 0:1 with codec_id 2 11:04:36 T:1458620816 DEBUG: CActiveAESink::OpenSink - trying to open device AUDIOTRACK:AudioTrack 11:04:36 T:1524670280 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 1:2 with codec_id 86016 11:04:36 T:1524670280 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 2:3 with codec_id 94215 11:04:36 T:1524670280 NOTICE: Opening video stream: 0 source: 256 11:04:36 T:1524670280 NOTICE: Creating video codec with codec id: 2 11:04:36 T:1524670280 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no AMCodec:yes MediaCodec:yes OpenMax:no libstagefright:yes VDPAU:no VAAPI:no 11:04:36 T:1524670280 INFO: MediaCodec Video Decoder... 11:04:36 T:1524670280 DEBUG: FactoryCodec - Video: mediacodec - Opening 11:04:36 T:1458620816 DEBUG: CActiveAESink::OpenSink - AUDIOTRACK Initialized: 11:04:36 T:1458620816 DEBUG: Output Device : android 11:04:36 T:1458620816 DEBUG: Sample Rate : 48000 11:04:36 T:1458620816 DEBUG: Sample Format : AE_FMT_S16NE 11:04:36 T:1458620816 DEBUG: Channel Count : 2 11:04:36 T:1458620816 DEBUG: Channel Layout: FL,FR 11:04:36 T:1458620816 DEBUG: Frames : 1536 11:04:36 T:1458620816 DEBUG: Frame Samples : 3072 11:04:36 T:1458620816 DEBUG: Frame Size : 4 11:04:36 T:1458656968 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 11:04:36 T:1524670280 DEBUG: Previous line repeats 1 times. 11:04:36 T:1524670280 DEBUG: CDVDVideoCodecAndroidMediaCodec::Open m_codecname(OMX.qcom.video.decoder.mpeg2), colorFormat(2141391875) 11:04:36 T:1524670280 DEBUG: CDVDVideoCodecAndroidMediaCodec::Open m_codecname(OMX.qcom.video.decoder.mpeg2), colorFormat(19) 11:04:36 T:1524670280 DEBUG: FactoryCodec - Video: amc-mpeg2 - Failed 11:04:36 T:1524670280 DEBUG: FactoryCodec - Video: stf-xxxx - Opening 11:04:36 T:1524670280 DEBUG: CDVDVideoCodecStageFright::virtual bool CDVDVideoCodecStageFright::Open(CDVDStreamInfo&, CDVDCodecOptions&) - trying to open, codec(2), profile(-99), level(-99) 11:04:36 T:1524670280 DEBUG: CStageFrightVideo::bool CStageFrightVideo::Open(CDVDStreamInfo&) - component: OMX.qcom.video.decoder.mpeg2 11:04:36 T:1524670280 DEBUG: FactoryCodec - Video: stf-mpeg2 - Opened 11:04:36 T:1528981856 NOTICE: Thread CStageFrightDecodeThread start, auto delete: true 11:04:36 T:1524670280 NOTICE: Creating video thread 11:04:36 T:1515803280 NOTICE: Thread DVDPlayerVideo start, auto delete: false 11:04:36 T:1515803280 NOTICE: running thread: video_thread 11:04:36 T:1524670280 NOTICE: Opening audio stream: 1 source: 256 11:04:36 T:1524670280 NOTICE: Finding audio codec for: 86016 11:04:36 T:1515803280 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 11:04:36 T:1524670280 DEBUG: FactoryCodec - Audio: passthrough - Opening 11:04:36 T:1524670280 DEBUG: FactoryCodec - Audio: passthrough - Failed 11:04:36 T:1524670280 DEBUG: FactoryCodec - Audio: libmad - Opening 11:04:36 T:1524670280 DEBUG: SECTION:LoadDLL(libmad.so) 11:04:36 T:1524670280 DEBUG: Loading: /data/app-lib/org.xbmc.xbmc-1/libmad.so 11:04:36 T:1524670280 DEBUG: FactoryCodec - Audio: libmad - Opened 11:04:36 T:1524670280 NOTICE: Creating audio thread 11:04:36 T:1535971464 NOTICE: Thread DVDPlayerAudio start, auto delete: false 11:04:36 T:1535971464 NOTICE: running thread: CDVDPlayerAudio::Process() 11:04:36 T:1524670280 NOTICE: Opening teletext stream: 2 source: 256 11:04:36 T:1524670280 NOTICE: Creating teletext data thread 11:04:36 T:1515829336 NOTICE: Thread DVDTeletextData start, auto delete: false 11:04:36 T:1515829336 NOTICE: running thread: CDVDTeletextData 11:04:36 T:1515803280 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(48907823744.000000, 1) 11:04:36 T:1515803280 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback 11:04:36 T:1524670280 DEBUG: void CDVDDemuxPVRClient::ParsePacket(DemuxPacket*) - {1} iChannels changed from 0 to 2 11:04:36 T:1524670280 DEBUG: void CDVDDemuxPVRClient::ParsePacket(DemuxPacket*) - {1} iSampleRate changed from 0 to 48000 11:04:36 T:1524670280 NOTICE: Opening audio stream: 1 source: 256 11:04:36 T:1524670280 NOTICE: Finding audio codec for: 86016 11:04:36 T:1524670280 DEBUG: FactoryCodec - Audio: passthrough - Opening 11:04:36 T:1524670280 DEBUG: FactoryCodec - Audio: passthrough - Failed 11:04:36 T:1524670280 DEBUG: FactoryCodec - Audio: libmad - Opening 11:04:36 T:1524670280 DEBUG: FactoryCodec - Audio: libmad - Opened 11:04:36 T:1535971464 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(48907663888.000000, 0) 11:04:36 T:1535971464 DEBUG: CDVDPlayerAudio: Sample rate changed, checking for passthrough 11:04:36 T:1535971464 DEBUG: FactoryCodec - Audio: passthrough - Opening 11:04:36 T:1535971464 DEBUG: FactoryCodec - Audio: passthrough - Failed 11:04:36 T:1535971464 DEBUG: FactoryCodec - Audio: libmad - Opening 11:04:36 T:1535971464 DEBUG: FactoryCodec - Audio: libmad - Opened 11:04:36 T:1535971464 NOTICE: Creating audio stream (codec id: 86016, channels: 2, sample rate: 48000, no pass-through) 11:04:36 T:1535971464 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback 11:04:36 T:1458620816 INFO: CActiveAESink::OpenSink - initialize sink 11:04:36 T:1458620816 DEBUG: CActiveAESink::OpenSink - trying to open device AUDIOTRACK:AudioTrack 11:04:36 T:1458620816 DEBUG: CActiveAESink::OpenSink - AUDIOTRACK Initialized: 11:04:36 T:1458620816 DEBUG: Output Device : android 11:04:36 T:1458620816 DEBUG: Sample Rate : 48000 11:04:36 T:1458620816 DEBUG: Sample Format : AE_FMT_S16NE 11:04:36 T:1458620816 DEBUG: Channel Count : 2 11:04:36 T:1458620816 DEBUG: Channel Layout: FL,FR 11:04:36 T:1458620816 DEBUG: Frames : 1536 11:04:36 T:1458620816 DEBUG: Frame Samples : 3072 11:04:36 T:1458620816 DEBUG: Frame Size : 4 11:04:36 T:1458656968 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 11:04:36 T:1524670280 DEBUG: CDVDPlayer::HandleMessages - player started 1 11:04:37 T:1524670280 NOTICE: Opening video stream: 0 source: 256 11:04:37 T:1524670280 NOTICE: Creating video codec with codec id: 2 11:04:37 T:1524670280 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no AMCodec:yes MediaCodec:yes OpenMax:no libstagefright:yes VDPAU:no VAAPI:no 11:04:37 T:1524670280 INFO: MediaCodec Video Decoder... 11:04:37 T:1524670280 DEBUG: FactoryCodec - Video: mediacodec - Opening