18:55:24 T:1747504680  NOTICE: special://profile/ is mapped to: special://masterprofile/
18:55:24 T:1747504680  NOTICE: -----------------------------------------------------------------------
18:55:24 T:1747504680  NOTICE: Starting XBMC (13.3-MINIX(3) Git:20141213-db410c2). Platform: ARM Android 32-bit
18:55:24 T:1747504680  NOTICE: Using Debug XBMC x32 build, compiled Dec 13 2014 by GCC 4.8.0 for ARM Android 32-bit API level 14
18:55:24 T:1747504680  NOTICE: Running on Android 32-bit API level 19 (Android, 3.10.33 armv7l)
18:55:24 T:1747504680  NOTICE: Host CPU: ARMv7 Processor rev 1 (v7l), 2 cores available
18:55:24 T:1747504680  NOTICE: Product: k200, Device: NEO-X8, Board: NEO-X8 - Manufacturer: MBX, Brand: MINIX, Model: NEO-X8-H, Hardware: amlogic
18:55:24 T:1747504680  NOTICE: ARM Features: Neon enabled
18:55:24 T:1747504680  NOTICE: special://xbmc/ is mapped to: /data/data/hk.minix.xbmc/cache/apk/assets
18:55:24 T:1747504680  NOTICE: special://xbmcbin/ is mapped to: /data/data/hk.minix.xbmc/cache/apk/assets
18:55:24 T:1747504680  NOTICE: special://masterprofile/ is mapped to: /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/userdata
18:55:24 T:1747504680  NOTICE: special://home/ is mapped to: /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc
18:55:24 T:1747504680  NOTICE: special://temp/ is mapped to: /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/temp
18:55:24 T:1747504680  NOTICE: The executable running is:
18:55:24 T:1747504680  NOTICE: Local hostname: localhost
18:55:24 T:1747504680  NOTICE: Log File is located: /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/temp/xbmc.log
18:55:24 T:1747504680  NOTICE: -----------------------------------------------------------------------
18:55:24 T:1747504680  NOTICE: load settings...
18:55:24 T:1747504680   DEBUG: static int CAndroidFeatures::GetVersion(): android.os.Build.VERSION 19
18:55:24 T:1747504680  NOTICE: aml_present, rtn(0)
18:55:24 T:1747504680   DEBUG: CSettings: loaded settings definition from special://xbmc/system/settings/settings.xml
18:55:25 T:1747504680   DEBUG: CSettings: loaded settings definition from special://xbmc/system/settings/android.xml
18:55:25 T:1747504680  NOTICE: Found 1 Lists of Devices
18:55:25 T:1747504680  NOTICE: Enumerated AUDIOTRACK devices:
18:55:25 T:1747504680  NOTICE:     Device 1
18:55:25 T:1747504680  NOTICE:         m_deviceName      : AudioTrack
18:55:25 T:1747504680  NOTICE:         m_displayName     : android
18:55:25 T:1747504680  NOTICE:         m_displayNameExtra: audiotrack
18:55:25 T:1747504680  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI
18:55:25 T:1747504680  NOTICE:         m_channels        : FL,FR
18:55:25 T:1747504680  NOTICE:         m_sampleRates     : 48000,48000
18:55:25 T:1747504680  NOTICE:         m_dataFormats     : AE_FMT_S16LE,AE_FMT_AC3,AE_FMT_DTS
18:55:25 T:1747504680   DEBUG: CSettingsManager: requested setting (has_fakeaudiodevices) was not found.
18:55:25 T:1747504680  NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml)
18:55:25 T:1747504680  NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
18:55:25 T:1747504680  NOTICE: Default DVD Player: dvdplayer
18:55:25 T:1747504680  NOTICE: Default Video Player: dvdplayer
18:55:25 T:1747504680  NOTICE: Default Audio Player: paplayer
18:55:25 T:1747504680  NOTICE: Disabled debug logging due to GUI setting. Level 0.
18:55:25 T:1747504680  NOTICE: Log level changed to 0
18:55:25 T:1747504680  NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
18:55:25 T:1747504680  NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
18:55:25 T:1747504680   DEBUG: CPlayerCoreConfig::<ctor>: created player DVDPlayer for core 1
18:55:25 T:1747504680   DEBUG: CPlayerCoreConfig::<ctor>: created player oldmplayercore for core 1
18:55:25 T:1747504680   DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer for core 3
18:55:25 T:1747504680   DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
18:55:25 T:1747504680   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtv
18:55:25 T:1747504680   DEBUG: CPlayerSelectionRule::Initialize: creating rule: hdhomerun/myth/mms/udp
18:55:25 T:1747504680   DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
18:55:25 T:1747504680   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
18:55:25 T:1747504680   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
18:55:25 T:1747504680   DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
18:55:25 T:1747504680   DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
18:55:25 T:1747504680   DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
18:55:25 T:1747504680   DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
18:55:25 T:1747504680   DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
18:55:25 T:1747504680   DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
18:55:25 T:1747504680   DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
18:55:25 T:1747504680   DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
18:55:25 T:1747504680  NOTICE: Loaded playercorefactory configuration
18:55:25 T:1747504680  NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
18:55:25 T:1747504680  NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
18:55:25 T:1747504680    INFO: creating subdirectories
18:55:25 T:1747504680    INFO: userdata folder: special://masterprofile/
18:55:25 T:1747504680    INFO: recording folder:
18:55:25 T:1747504680    INFO: screenshots folder:
18:55:25 T:1747504680    INFO: load language info file: special://xbmc/language/German/langinfo.xml
18:55:25 T:1747504680   DEBUG: trying to set locale to de_DE.UTF-8
18:55:25 T:1747504680    INFO: global locale set to C
18:55:25 T:1747504680    INFO: load German language file, from path: special://xbmc/language/
18:55:25 T:1747504680   DEBUG: POParser: loaded 3162 strings from file /data/data/hk.minix.xbmc/cache/apk/assets/language/German/strings.po
18:55:25 T:1747504680   DEBUG: POParser: loaded 2 strings from file /data/data/hk.minix.xbmc/cache/apk/assets/language/English/strings.po
18:55:25 T:1738905240  NOTICE: Thread ActiveAE start, auto delete: false
18:55:25 T:1738722768  NOTICE: Thread AESink start, auto delete: false
18:55:25 T:1738722768    INFO: CActiveAESink::OpenSink - initialize sink
18:55:25 T:1738722768   DEBUG: CActiveAESink::OpenSink - trying to open device AUDIOTRACK:AudioTrack
18:55:25 T:1738722768   DEBUG: CActiveAESink::OpenSink - AUDIOTRACK Initialized:
18:55:25 T:1738722768   DEBUG:   Output Device : android
18:55:25 T:1738722768   DEBUG:   Sample Rate   : 44100
18:55:25 T:1738722768   DEBUG:   Sample Format : AE_FMT_S16NE
18:55:25 T:1738722768   DEBUG:   Channel Count : 2
18:55:25 T:1738722768   DEBUG:   Channel Layout: FL,FR
18:55:25 T:1738722768   DEBUG:   Frames        : 1881
18:55:25 T:1738722768   DEBUG:   Frame Samples : 3762
18:55:25 T:1738722768   DEBUG:   Frame Size    : 4
18:55:25 T:1747504680  NOTICE: Running database version Addons16
18:55:25 T:1747504680   DEBUG: SECTION:LoadDLL(libcpluff-arm.so)
18:55:25 T:1747504680   DEBUG: Loading: /data/app-lib/hk.minix.xbmc-1/libcpluff-arm.so
18:55:25 T:1747504680  NOTICE: ADDONS: Using repository repository.addonscriptorde-beta
18:55:25 T:1747504680  NOTICE: ADDONS: Using repository repository.membrane.xbmc-plugins
18:55:25 T:1747504680  NOTICE: ADDONS: Using repository repository.xbmc.org
18:55:25 T:1747504680  NOTICE: ADDONS: Using repository repository.bromix
18:55:25 T:1747504680  NOTICE: ADDONS: Using repository repository.xycl.addons
18:55:25 T:1747504680  NOTICE: ADDONS: Using repository repository.kodinerds.helix
18:55:25 T:1747504680  NOTICE: ADDONS: Using repository repository.pvr-android.xbmc.org
18:55:25 T:1747504680   DEBUG: bool PERIPHERALS::CPeripherals::LoadMappings() - loaded node "Motorola Nyxboard Hybrid"
18:55:25 T:1747504680   DEBUG: bool PERIPHERALS::CPeripherals::LoadMappings() - loaded node "CEC Adapter"
18:55:25 T:1747504680   DEBUG: bool PERIPHERALS::CPeripherals::LoadMappings() - loaded node "Pulse-Eight CEC Adapter"
18:55:25 T:1747504680   DEBUG: bool PERIPHERALS::CPeripherals::LoadMappings() - loaded node "iMON HID device"
18:55:25 T:1747504680   DEBUG: PERIPHERALS::CPeripheralBusUSB::CPeripheralBusUSB(PERIPHERALS::CPeripherals*) - using libusb peripheral scanning
18:55:25 T:1747504680   DEBUG: SECTION:LoadDLL(libcec.so)
18:55:25 T:1747504680   DEBUG: Loading: libcec.so
18:55:25 T:1747504680   ERROR: Unable to load libcec.so, reason: (null)
18:55:25 T:1747504680   DEBUG: Dll libcec.so was not found in path
18:55:25 T:1747504680   ERROR: virtual void PERIPHERALS::CPeripherals::Initialise() - failed to initialise bus cec
18:55:25 T:1744791736  NOTICE: Thread PeripBusUSB start, auto delete: false
18:55:25 T:1747504680  NOTICE: virtual bool CWinSystemEGL::InitWindowSystem(): Using EGL Implementation: amlandroid
18:55:25 T:1747504680  NOTICE: Current resolution: 1920x1080 @ 60.00 - Full Screen
18:55:25 T:1747504680  NOTICE: aml_get_sysfs_str k=/sys/class/amhdmitx/amhdmitx0/disp_cap v=480i
                                            480p
                                            576i
                                            576p*
                                            720p
                                            1080i
                                            720p50hz
                                            1080i50hz
18:55:25 T:1747504680  NOTICE: Current resolution: 1920x1080 @ 60.00 - Full Screen
18:55:25 T:1747504680  NOTICE: aml_get_sysfs_str k=/sys/class/display/mode v=720p
18:55:25 T:1747504680  NOTICE: Desktop resolution 1920 x 1080 for display 0 with 1280 x 720 @ 60.000000 Hz
18:55:25 T:1747504680  NOTICE: Found resolution 1920 x 1080 for display 0 with 1280 x 720 @ 60.000000 Hz
18:55:25 T:1747504680  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080i @ 60.000000 Hz
18:55:25 T:1747504680  NOTICE: Found resolution 1920 x 1080 for display 0 with 1280 x 720 @ 50.000000 Hz
18:55:25 T:1747504680  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080i @ 50.000000 Hz
18:55:25 T:1747504680  NOTICE: Found (1920x1080@60.000000) at 16, setting to RES_DESKTOP at 16
18:55:25 T:1747504680  NOTICE: Checking resolution 16
18:55:25 T:1747504680  NOTICE: aml_set_sysfs_int k=/sys/class/display/mode v=720p
18:55:25 T:1747504680   DEBUG: bool CWinSystemEGL::CreateWindow(RESOLUTION_INFO&): Created surface of size 1920x1080
18:55:25 T:1747504680  NOTICE: GL_VENDOR = ARM
18:55:25 T:1747504680  NOTICE: GL_RENDERER = Mali-450 MP
18:55:25 T:1747504680  NOTICE: GL_VERSION = OpenGL ES 2.0
18:55:25 T:1747504680  NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 1.00
18:55:25 T:1747504680   DEBUG: GLES: Extension Support Test - GL_NVX_gpu_memory_info NO
18:55:25 T:1747504680  NOTICE: GL_EXTENSIONS = GL_EXT_debug_marker GL_OES_texture_npot GL_OES_compressed_ETC1_RGB8_texture GL_OES_standard_derivatives GL_OES_EGL_image GL_OES_depth24 GL_ARM_rgba8 GL_ARM_mali_shader_binary GL_OES_depth_texture GL_OES_packed_depth_stencil GL_EXT_texture_format_BGRA8888 GL_OES_vertex_half_float GL_EXT_blend_minmax GL_OES_EGL_image_external GL_OES_EGL_sync GL_OES_rgb8_rgba8 GL_EXT_multisampled_render_to_texture GL_EXT_discard_framebuffer GL_OES_get_program_binary GL_ARM_mali_program_binary GL_EXT_shader_texture_lod GL_EXT_robustness GL_OES_depth_texture_cube_map GL_KHR_debug
18:55:25 T:1747504680   DEBUG: GLES: Extension Support Test - GL_EXT_texture_format_BGRA8888 YES
18:55:25 T:1747504680   DEBUG: GLES: Extension Support Test - GL_IMG_texture_format_BGRA8888 NO
18:55:25 T:1747504680   DEBUG: GLES: Extension Support Test - GL_APPLE_texture_format_BGRA8888 NO
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GL: Pixel Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Fragment Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GUI Shader [guishader_frag_default.glsl]- Initialise successful : 0x67ffba80
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GL: Pixel Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Fragment Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GUI Shader [guishader_frag_texture.glsl]- Initialise successful : 0x68113f80
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GL: Pixel Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Fragment Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GUI Shader [guishader_frag_multi.glsl]- Initialise successful : 0x6811a768
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GL: Pixel Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Fragment Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GUI Shader [guishader_frag_fonts.glsl]- Initialise successful : 0x68120da0
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GL: Pixel Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Fragment Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GUI Shader [guishader_frag_texture_noblend.glsl]- Initialise successful : 0x68127320
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GL: Pixel Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Fragment Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GUI Shader [guishader_frag_multi_blendcolor.glsl]- Initialise successful : 0x68128880
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GL: Pixel Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Fragment Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GUI Shader [guishader_frag_rgba.glsl]- Initialise successful : 0x6812fbb0
18:55:25 T:1747504680   DEBUG: GLES: Extension Support Test - GL_OES_EGL_image_external YES
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GL: Pixel Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Fragment Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GUI Shader [guishader_frag_rgba_oes.glsl]- Initialise successful : 0x68136310
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Vertex Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GL: Pixel Shader compilation log:
18:55:25 T:1747504680   DEBUG: GL: Fragment Shader compiled successfully
18:55:25 T:1747504680   DEBUG: GUI Shader [guishader_frag_rgba_blendcolor.glsl]- Initialise successful : 0x68137ab8
18:55:25 T:1747504680  NOTICE: Current resolution: 1920x1080 @ 60.00 - Full Screen
18:55:25 T:1747504680  NOTICE: aml_get_sysfs_str k=/sys/class/display/mode v=720p
18:55:25 T:1747504680   DEBUG: CWinSystemEGL::CreateNewWindow: No need to create a new window
18:55:25 T:1747504680    INFO: GLES: Maximum texture width: 4096
18:55:25 T:1747504680   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
18:55:25 T:1747504680    INFO: load default splash image: /data/data/hk.minix.xbmc/cache/apk/assets/media/Splash.png
18:55:25 T:1747504680   DEBUG: SECTION:LoadDLL(libImageLib-arm.so)
18:55:25 T:1747504680   DEBUG: Loading: /data/app-lib/hk.minix.xbmc-1/libImageLib-arm.so
18:55:25 T:1747504680    INFO: load keymapping
18:55:25 T:1747504680    INFO: Loading special://xbmc/system/keymaps/appcommand.xml
18:55:25 T:1747504680    INFO: Loading special://xbmc/system/keymaps/gamepad.xml
18:55:25 T:1747504680    INFO: Loading special://xbmc/system/keymaps/joystick.xml
18:55:25 T:1747504680  NOTICE: No Joystick name specified, loading default map
18:55:25 T:1747504680  NOTICE: Previous line repeats 35 times.
18:55:25 T:1747504680    INFO: Loading special://xbmc/system/keymaps/keyboard.xml
18:55:25 T:1747504680    INFO: Loading special://xbmc/system/keymaps/mouse.xml
18:55:25 T:1747504680    INFO: Loading special://xbmc/system/keymaps/remote.xml
18:55:25 T:1747504680    INFO: Loading special://xbmc/system/keymaps/touchscreen.xml
18:55:25 T:1747504680    INFO: GUI format 1920x1080, Display 1280x720 @ 60.00 - Full Screen
18:55:25 T:1747504680   DEBUG: guilib: Fill viewport on change for solving rendering passes
18:55:25 T:1747504680   DEBUG: SECTION:LoadDLL(libxbcurl.so)
18:55:25 T:1747504680   DEBUG: Loading: /data/app-lib/hk.minix.xbmc-1/libxbcurl.so
18:55:25 T:1747504680  NOTICE: Running database version Addons16
18:55:25 T:1747504680   DEBUG: void CDatabaseManager::Initialize(bool), updating databases...
18:55:25 T:1747504680  NOTICE: Running database version ViewModes6
18:55:25 T:1747504680  NOTICE: Running database version Textures13
18:55:25 T:1747504680  NOTICE: Running database version MyMusic46
18:55:25 T:1747504680  NOTICE: Running database version MyVideos78
18:55:25 T:1747504680  NOTICE: Running database version TV22
18:55:25 T:1747504680  NOTICE: Running database version Epg7
18:55:25 T:1747504680   DEBUG: void CDatabaseManager::Initialize(bool), updating databases... DONE
18:55:25 T:1747504680    INFO: DPMS: not supported on this platform
18:55:25 T:1747504680   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
18:55:25 T:1747504680   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
18:55:25 T:1747504680    INFO: Unloading old skin ...
18:55:25 T:1747504680    INFO:   load skin from: /data/data/hk.minix.xbmc/cache/apk/assets/addons/skin.confluence (version: 2.2.20)
18:55:25 T:1747504680    INFO:   load fonts for skin...
18:55:25 T:1747504680    INFO: Loading fonts from /data/data/hk.minix.xbmc/cache/apk/assets/addons/skin.confluence/720p/Font.xml
18:55:25 T:1747504680   DEBUG: POParser: loaded 148 strings from file /data/data/hk.minix.xbmc/cache/apk/assets/addons/skin.confluence/language/German/strings.po
18:55:25 T:1747504680   DEBUG: POParser: loaded 0 strings from file /data/data/hk.minix.xbmc/cache/apk/assets/addons/skin.confluence/language/English/strings.po
18:55:25 T:1747504680    INFO: Loading skin includes from /data/data/hk.minix.xbmc/cache/apk/assets/addons/skin.confluence/720p/includes.xml
18:55:25 T:1747504680    INFO:   load new skin...
18:55:25 T:1747504680    INFO: Loading user windows, path /data/data/hk.minix.xbmc/cache/apk/assets/addons/skin.confluence/720p
18:55:25 T:1747504680   DEBUG: Load Skin XML: 304.54ms
18:55:26 T:1747504680    INFO:   initialize new skin...
18:55:26 T:1747504680   DEBUG: guilib: Fill viewport on change for solving rendering passes
18:55:26 T:1747504680    INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
18:55:26 T:1747504680   DEBUG: Load Pointer.xml: 6.14ms
18:55:26 T:1747504680   DEBUG: bool CTextureBundleXBT::OpenBundle() - Opened bundle /data/data/hk.minix.xbmc/cache/apk/assets/addons/skin.confluence/media/Textures.xbt
18:55:26 T:1747504680   DEBUG: Alloc resources: 19.63ms  (6.69 ms skin load)
18:55:26 T:1747504680    INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
18:55:26 T:1747504680   DEBUG: Load DialogVolumeBar.xml: 9.96ms
18:55:26 T:1747504680   DEBUG: Alloc resources: 11.08ms  (10.60 ms skin load)
18:55:26 T:1747504680    INFO: Loading skin file: DialogKaiToast.xml, load type: LOAD_ON_GUI_INIT
18:55:26 T:1747504680   DEBUG: Load DialogKaiToast.xml: 11.24ms
18:55:26 T:1747504680   DEBUG: Alloc resources: 11.97ms  (11.97 ms skin load)
18:55:26 T:1747504680    INFO: Loading skin file: DialogMuteBug.xml, load type: LOAD_ON_GUI_INIT
18:55:26 T:1747504680   DEBUG: Load DialogMuteBug.xml: 2.64ms
18:55:26 T:1747504680   DEBUG: Alloc resources: 3.12ms  (3.12 ms skin load)
18:55:26 T:1747504680    INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
18:55:26 T:1747504680   DEBUG: Load DialogSeekBar.xml: 37.94ms
18:55:26 T:1747504680   DEBUG: Alloc resources: 38.34ms  (38.12 ms skin load)
18:55:26 T:1747504680    INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
18:55:26 T:1747504680   DEBUG: Load DialogBusy.xml: 1.46ms
18:55:26 T:1747504680   DEBUG: Alloc resources: 1.61ms  (1.60 ms skin load)
18:55:26 T:1747504680    INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
18:55:26 T:1747504680   DEBUG: Load DialogExtendedProgressBar.xml: 1.60ms
18:55:26 T:1747504680   DEBUG: Alloc resources: 1.77ms  (1.76 ms skin load)
18:55:26 T:1747504680    INFO: Loading /data/data/hk.minix.xbmc/cache/apk/assets/addons/skin.confluence/sounds/sounds.xml
18:55:26 T:1747504680    INFO:   skin loaded...
18:55:26 T:1747504680   DEBUG: JSONRPC: JSON schema type stereoscopicmode references an unknown type GUI.Stereoscopy.Mode
18:55:26 T:1747504680 WARNING: JSONRPC: Could not parse type "GUI.Property.Value"
18:55:26 T:1747504680    INFO: JSONRPC: Adding type "GUI.Property.Value" to list of incomplete definitions (waiting for "GUI.Stereoscopy.Mode")
18:55:26 T:1747504680    INFO: JSONRPC: Resolving incomplete types/methods referencing GUI.Stereoscopy.Mode
18:55:26 T:1747504680   DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
18:55:26 T:1747504680 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
18:55:26 T:1747504680    INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
18:55:26 T:1747504680    INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
18:55:26 T:1747504680    INFO: JSONRPC v6.14.3: Successfully initialized
18:55:26 T:1747504680   DEBUG: ADDON: Starting service addons.
18:55:26 T:1747504680   DEBUG: Activating window ID: 12999
18:55:26 T:1747504680   DEBUG: ------ Window Init (Startup.xml) ------
18:55:26 T:1747504680    INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
18:55:26 T:1778687880  NOTICE: Thread LanguageInvoker start, auto delete: false
18:55:26 T:1778687880    INFO: initializing python engine.
18:55:26 T:1778686744  NOTICE: Thread LanguageInvoker start, auto delete: false
18:55:26 T:1778686744    INFO: initializing python engine.
18:55:26 T:1747504680   DEBUG: Load Startup.xml: 3.48ms
18:55:26 T:1747504680   DEBUG: Alloc resources: 8.57ms  (8.57 ms skin load)
18:55:26 T:1747504680    INFO: removing tempfiles
18:55:26 T:1747504680   DEBUG: ADDON: Starting service addons.
18:55:26 T:1747504680  NOTICE: initialize done
18:55:26 T:1747504680  NOTICE: Running the application...
18:55:26 T:1747504680   DEBUG: Activating window ID: 10000
18:55:26 T:1783409888  NOTICE: Thread LanguageInvoker start, auto delete: false
18:55:26 T:1783409888    INFO: initializing python engine.
18:55:26 T:1747504680    INFO: GLES: Enabling VSYNC
18:55:26 T:1747504680    INFO: GLES: Selected vsync mode 10
18:55:26 T:1747504680   DEBUG: ------ Window Deinit (Startup.xml) ------
18:55:26 T:1747504680   DEBUG: ------ Window Init (Home.xml) ------
18:55:26 T:1747504680    INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
18:55:26 T:1778686744   DEBUG: CPythonInvoker(0, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/service.skin.widgets/default.py): start processing
18:55:26 T:1783409888   DEBUG: CPythonInvoker(2, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache/default.py): start processing
18:55:26 T:1778687880   DEBUG: CPythonInvoker(1, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.tv.show.next.aired/service.py): start processing
18:55:26 T:1778686744  NOTICE: -->Python Interpreter Initialized<--
18:55:26 T:1778686744   DEBUG: CPythonInvoker(0, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/service.skin.widgets/default.py): the source file to load is "/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/service.skin.widgets/default.py"
18:55:26 T:1783409888  NOTICE: -->Python Interpreter Initialized<--
18:55:26 T:1783409888   DEBUG: CPythonInvoker(2, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache/default.py): the source file to load is "/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache/default.py"
18:55:26 T:1783409888   DEBUG: CPythonInvoker(2, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache/default.py): setting the Python path to /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.pyamf/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.simplejson/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.beautifulsoup4/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/script.module.pil/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.requests/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.mechanize/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/xbmc.debug/:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.xbmcswift2/lib:/:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python26.zip:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/plat-linux3:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-tk:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-old:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-dynload
18:55:26 T:1783409888   DEBUG: CPythonInvoker(2, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache/default.py): entering source directory /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache
18:55:26 T:1783409888   DEBUG: CPythonInvoker(2, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache/default.py): instantiating addon using automatically obtained id of "script.common.plugin.cache" dependent on version 2.1.0 of the xbmc.python api
18:55:26 T:1778686744   DEBUG: CPythonInvoker(0, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/service.skin.widgets/default.py): setting the Python path to /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/service.skin.widgets:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.pyamf/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.simplejson/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.beautifulsoup4/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/script.module.pil/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.requests/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.mechanize/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/xbmc.debug/:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.xbmcswift2/lib:/:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python26.zip:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/plat-linux3:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-tk:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-old:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-dynload
18:55:26 T:1778686744   DEBUG: CPythonInvoker(0, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/service.skin.widgets/default.py): entering source directory /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/service.skin.widgets
18:55:26 T:1778686744   DEBUG: CPythonInvoker(0, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/service.skin.widgets/default.py): instantiating addon using automatically obtained id of "service.skin.widgets" dependent on version 2.1.0 of the xbmc.python api
18:55:26 T:1778687880  NOTICE: -->Python Interpreter Initialized<--
18:55:26 T:1778687880   DEBUG: CPythonInvoker(1, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.tv.show.next.aired/service.py): the source file to load is "/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.tv.show.next.aired/service.py"
18:55:26 T:1778687880   DEBUG: CPythonInvoker(1, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.tv.show.next.aired/service.py): setting the Python path to /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.tv.show.next.aired:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.pyamf/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.simplejson/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.beautifulsoup4/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/script.module.pil/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.requests/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.mechanize/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/xbmc.debug/:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.xbmcswift2/lib:/:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python26.zip:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/plat-linux3:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-tk:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-old:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-dynload
18:55:26 T:1778687880   DEBUG: CPythonInvoker(1, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.tv.show.next.aired/service.py): entering source directory /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.tv.show.next.aired
18:55:26 T:1778687880   DEBUG: CPythonInvoker(1, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.tv.show.next.aired/service.py): instantiating addon using automatically obtained id of "script.tv.show.next.aired" dependent on version 2.1.0 of the xbmc.python api
18:55:26 T:1747504680   DEBUG: Load Home.xml: 189.98ms
18:55:26 T:1747504680   DEBUG: Alloc resources: 195.94ms  (190.15 ms skin load)
18:55:26 T:1787824352  NOTICE: Thread JobWorker start, auto delete: true
18:55:26 T:1787824352   DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
18:55:26 T:1747504680   DEBUG: no profile autoexec.py (/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/userdata/autoexec.py) found, skipping
18:55:26 T:1747504680   DEBUG: void CNetwork::NetworkMessage(CNetwork::EMESSAGE, int) - Starting network services
18:55:26 T:1747504680  NOTICE: starting zeroconf publishing
18:55:26 T:1747504680  NOTICE: Webserver: Starting...
18:55:26 T:1785131256  NOTICE: Thread ZerocconfEmbedded start, auto delete: false
18:55:26 T:1785131256   DEBUG: ZeroconfEmbedded - processing...
18:55:26 T:1747504680  NOTICE: WebServer: Started the webserver
18:55:26 T:1747504680  NOTICE: ES: Starting event server
18:55:26 T:1787790840  NOTICE: Thread JobWorker start, auto delete: true
18:55:26 T:1747504680    INFO: JSONRPC Server: Successfully initialized
18:55:26 T:1784912040  NOTICE: Thread TCPServer start, auto delete: false
18:55:26 T:1785127008  NOTICE: Thread JobWorker start, auto delete: true
18:55:26 T:1787790840   DEBUG: ZeroconfMDNS: identifier: servers.webserver type: _http._tcp name:XBMC_mcflay port:8080
18:55:26 T:1787790840   DEBUG: ZeroconfMDNS: identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:XBMC_mcflay port:9090
18:55:26 T:1747504680  NOTICE: Current resolution: 1920x1080 @ 60.00 - Full Screen
18:55:26 T:1785127008   DEBUG: ZeroconfMDNS: identifier: servers.jsonrpc-http type: _xbmc-jsonrpc-h._tcp name:XBMC_mcflay port:8080
18:55:26 T:1784912640  NOTICE: Thread EventServer start, auto delete: false
18:55:26 T:1747504680  NOTICE: aml_get_sysfs_str k=/sys/class/display/mode v=720p
18:55:26 T:1747504680   DEBUG: CWinSystemEGL::CreateNewWindow: No need to create a new window
18:55:26 T:1747504680  NOTICE: GL_VENDOR = ARM
18:55:26 T:1747504680  NOTICE: GL_RENDERER = Mali-450 MP
18:55:26 T:1747504680  NOTICE: GL_VERSION = OpenGL ES 2.0
18:55:26 T:1747504680  NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 1.00
18:55:26 T:1747504680   DEBUG: GLES: Extension Support Test - GL_NVX_gpu_memory_info NO
18:55:26 T:1747504680  NOTICE: GL_EXTENSIONS = GL_EXT_debug_marker GL_OES_texture_npot GL_OES_compressed_ETC1_RGB8_texture GL_OES_standard_derivatives GL_OES_EGL_image GL_OES_depth24 GL_ARM_rgba8 GL_ARM_mali_shader_binary GL_OES_depth_texture GL_OES_packed_depth_stencil GL_EXT_texture_format_BGRA8888 GL_OES_vertex_half_float GL_EXT_blend_minmax GL_OES_EGL_image_external GL_OES_EGL_sync GL_OES_rgb8_rgba8 GL_EXT_multisampled_render_to_texture GL_EXT_discard_framebuffer GL_OES_get_program_binary GL_ARM_mali_program_binary GL_EXT_shader_texture_lod GL_EXT_robustness GL_OES_depth_texture_cube_map GL_KHR_debug
18:55:26 T:1747504680   DEBUG: GLES: Extension Support Test - GL_EXT_texture_format_BGRA8888 YES
18:55:26 T:1747504680   DEBUG: GLES: Extension Support Test - GL_IMG_texture_format_BGRA8888 NO
18:55:26 T:1747504680   DEBUG: GLES: Extension Support Test - GL_APPLE_texture_format_BGRA8888 NO
18:55:26 T:1747504680   DEBUG: GUI Shader - Tried to Initialise again. Was this intentional?
18:55:26 T:1784912640  NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
18:55:26 T:1784912640  NOTICE: UDP: Listening on port 9777
18:55:26 T:1787790840   DEBUG: ZeroconfMDNS: identifier: servers.eventserver type: _xbmc-events._udp name:XBMC_mcflay port:9777
18:55:26 T:1784921144  NOTICE: Thread JobWorker start, auto delete: true
18:55:26 T:1747504680    INFO: GLES: Enabling VSYNC
18:55:26 T:1787487760  NOTICE: Thread RSSReader start, auto delete: false
18:55:26 T:1747504680    INFO: GLES: Selected vsync mode 10
18:55:26 T:1787487760   DEBUG: CurlFile::Open(0x70f89c80) http://feeds.xbmc.org/xbmc
18:55:26 T:1787487760    INFO: void XCURL::DllLibCurlGlobal::easy_aquire(const char*, const char*, XCURL::CURL_HANDLE**, XCURL::CURLM**) - Created session to http://feeds.xbmc.org
18:55:26 T:1787824352   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
18:55:26 T:1787824352   DEBUG: bool CMusicDatabase::GetRecentlyAddedAlbums(VECALBUMS&, unsigned int) query: select * from albumview where strAlbum != '' order by idAlbum desc limit 10
18:55:26 T:1787824352   DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
18:55:27 T:1787824352   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 30 ms for 10 items query: select * from movieview  ORDER BY dateAdded desc, idMovie desc LIMIT 10
18:55:27 T:1787790840   DEBUG: virtual bool CThumbExtractor::DoWork() - trying to extract filestream details from video file smb://FRITZ-NAS/FRITZ.NAS/TOSHIBA-MK7559GSXP-01/Video/Filme/Die drei Fragezeichen - Phonophobia.avi
18:55:27 T:1787824352   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 14 ms for 10 items query: select * from episodeview  ORDER BY dateAdded desc, idEpisode desc LIMIT 10
18:55:27 T:1787487760   DEBUG: Got rss feed: http://feeds.xbmc.org/xbmc
18:55:27 T:1787487760   DEBUG: RSS feed encoding: UTF-8
18:55:27 T:1787487760   DEBUG: Parsed rss feed: http://feeds.xbmc.org/xbmc
18:55:27 T:1787487760   DEBUG: CurlFile::Open(0x70f89c80) http://feeds.xbmc.org/latest_xbmc_addons
18:55:27 T:1783409888  NOTICE:  StorageServer Module loaded RUN
18:55:27 T:1783409888  NOTICE: StorageClient-2.5.4 Starting server
18:55:27 T:1787824352   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 1 ms for 0 items query: select * from musicvideoview  ORDER BY dateAdded desc, idMVideo desc LIMIT 10
18:55:27 T:1787824352   DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
18:55:27 T:1787487760   DEBUG: Got rss feed: http://feeds.xbmc.org/latest_xbmc_addons
18:55:27 T:1787487760   DEBUG: RSS feed encoding: UTF-8
18:55:27 T:1787487760   DEBUG: Parsed rss feed: http://feeds.xbmc.org/latest_xbmc_addons
18:55:27 T:1787487760   DEBUG: CurlFile::Open(0x70f89c80) http://feeds.xbmc.org/updated_xbmc_addons
18:55:27 T:1787487760   DEBUG: Got rss feed: http://feeds.xbmc.org/updated_xbmc_addons
18:55:27 T:1787487760   DEBUG: RSS feed encoding: UTF-8
18:55:27 T:1787487760   DEBUG: Parsed rss feed: http://feeds.xbmc.org/updated_xbmc_addons
18:55:27 T:1787487760   DEBUG: Thread RSSReader 1787487760 terminating
18:55:27 T:1778687880   DEBUG: POParser: loaded 42 strings from file /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.tv.show.next.aired/resources/language/German/strings.po
18:55:27 T:1778687880   DEBUG: POParser: loaded 0 strings from file /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.tv.show.next.aired/resources/language/English/strings.po
18:55:27 T:1778687880   DEBUG: script.tv.show.next.aired: ### params: {'service': 'true'}
18:55:27 T:1778687880  NOTICE: script.tv.show.next.aired: ### TV Show - Next Aired starting background proc (6.0.13)
18:55:27 T:1785131256   DEBUG: ZeroconfMDNS: XBMC_mcflay._http._tcp.local. registration removed
18:55:27 T:1785131256   DEBUG: ZeroconfMDNS: XBMC_mcflay._xbmc-jsonrpc._tcp.local. registration removed
18:55:27 T:1785131256   DEBUG: ZeroconfMDNS: XBMC_mcflay._xbmc-jsonrpc-h._tcp.local. registration removed
18:55:27 T:1785131256   DEBUG: ZeroconfMDNS: XBMC_mcflay._xbmc-events._udp.local. registration removed
18:55:28 T:1778687880   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 11 ms for 3 items query: SELECT * FROM tvshowview
18:55:28 T:1787790840   DEBUG: CSmbFile::Open - opened FRITZ.NAS/TOSHIBA-MK7559GSXP-01/Video/Filme/Die drei Fragezeichen - Phonophobia.avi, fd=10000
18:55:28 T:1778686744   DEBUG: Skin Widgets: script version 0.0.29 started
18:55:28 T:1778686744   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 21 ms for 67 items query: select * from movieview  WHERE ((movieview.playCount IS NULL OR movieview.playCount < 1))
18:55:28 T:1787790840   DEBUG: bool CDVDDemuxFFmpeg::Open(CDVDInputStream*) - probing detected format [avi]
18:55:29 T:1778686744   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 10 ms for 58 items query: select * from episodeview  WHERE ((episodeview.playCount IS NULL OR episodeview.playCount < 1))
18:55:30 T:1778686744   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 2 ms for 0 items query: select * from musicvideoview
18:55:30 T:1747504680   DEBUG: bool CApplication::ProcessMouse(): trying mouse action leftclick
18:55:30 T:1747504680   DEBUG: Activating window ID: 10025
18:55:30 T:1778686744   DEBUG: bool CMusicDatabase::GetAlbumsByWhere(const CStdString&, const CDatabase::Filter&, CFileItemList&, const SortDescription&, bool) query: SELECT albumview.* FROM albumview  WHERE albumview.strAlbum <> ''
18:55:30 T:1778686744   DEBUG: bool CMusicDatabase::GetAlbumsByWhere(const CStdString&, const CDatabase::Filter&, CFileItemList&, const SortDescription&, bool) - query took 1 ms
18:55:30 T:1778686744   DEBUG: bool CMusicDatabase::GetArtistsByWhere(const CStdString&, const CDatabase::Filter&, CFileItemList&, const SortDescription&, bool) query: SELECT artistview.* FROM artistview  WHERE (artistview.idArtist IN (SELECT song_artist.idArtist FROM song_artist) OR artistview.idArtist IN (SELECT album_artist.idArtist FROM album_artist)) and artistview.strArtist != '' and artistview.strArtist <> 'Verschiedene Interpreten'
18:55:30 T:1778686744   DEBUG: Time to retrieve artists from dataset = 2
18:55:30 T:1778686744   DEBUG: bool CMusicDatabase::GetSongsByWhere(const CStdString&, const CDatabase::Filter&, CFileItemList&, const SortDescription&) query = SELECT songview.* FROM songview  WHERE ((CAST(songview.iTimesPlayed as DECIMAL(5,1)) < 1))
18:55:30 T:1747504680   DEBUG: ------ Window Deinit (Home.xml) ------
18:55:30 T:1747504680   DEBUG: ------ Window Init (MyVideoNav.xml) ------
18:55:30 T:1747504680    INFO: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY
18:55:30 T:1747504680   DEBUG: Load MyVideoNav.xml: 151.75ms
18:55:30 T:1747504680   DEBUG: Alloc resources: 154.56ms  (152.00 ms skin load)
18:55:30 T:1747504680   DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.ardmediathek_de/)
18:55:30 T:1747504680   DEBUG:   ParentPath = [plugin://plugin.video.ardmediathek_de/]
18:55:30 T:1747504680   DEBUG: bool XFILE::CPluginDirectory::StartScript(const CStdString&, bool) - calling plugin ARD Mediathek('plugin://plugin.video.ardmediathek_de/','1','')
18:55:30 T:1747504680   DEBUG: bool XFILE::CPluginDirectory::WaitOnScriptResult(const CStdString&, int, const CStdString&, bool) - waiting on the ARD Mediathek (id=3) plugin...
18:55:30 T:1920227320  NOTICE: Thread LanguageInvoker start, auto delete: false
18:55:30 T:1920227320    INFO: initializing python engine.
18:55:30 T:1920227320   DEBUG: CPythonInvoker(3, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): start processing
18:55:30 T:1778686744   DEBUG: Skin Widgets: Total time needed to request random queries: 0:00:02.095953
18:55:30 T:1778686744   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 2 ms for 0 items query: select * from movieview  WHERE (movieview.idFile  IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
18:55:30 T:1920227320  NOTICE: -->Python Interpreter Initialized<--
18:55:30 T:1920227320   DEBUG: CPythonInvoker(3, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): the source file to load is "/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py"
18:55:30 T:1920227320   DEBUG: CPythonInvoker(3, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): setting the Python path to /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.pyamf/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.simplejson/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.beautifulsoup4/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/script.module.pil/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.requests/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.mechanize/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/xbmc.debug/:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.xbmcswift2/lib:/:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python26.zip:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/plat-linux3:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-tk:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-old:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-dynload
18:55:30 T:1920227320   DEBUG: CPythonInvoker(3, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): entering source directory /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de
18:55:30 T:1920227320   DEBUG: CPythonInvoker(3, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): instantiating addon using automatically obtained id of "plugin.video.ardmediathek_de" dependent on version 2.1.0 of the xbmc.python api
18:55:30 T:1778686744   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 12 ms for 2 items query: SELECT * FROM tvshowview  WHERE ( ((tvshowview.watchedcount > 0 AND tvshowview.watchedcount < tvshowview.totalCount) OR (tvshowview.watchedcount = 0 AND EXISTS (SELECT 1 FROM episodeview WHERE episodeview.idShow = tvshowview.idShow AND episodeview.resumeTimeInSeconds > 0))))
18:55:30 T:1778686744   DEBUG: bool CVideoDatabase::GetStackedTvShowList(int, CStdString&) const query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=3) order by idShow
18:55:30 T:1778686744   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 9 ms for 26 items query: select * from episodeview  WHERE (episodeview.idShow = 3) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
18:55:30 T:1778686744   DEBUG: DatabaseUtils::GetSortFieldList: unknown field 25
18:55:30 T:1778686744   DEBUG: bool CVideoDatabase::GetStackedTvShowList(int, CStdString&) const query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=2) order by idShow
18:55:30 T:1778686744   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 7 ms for 22 items query: select * from episodeview  WHERE (episodeview.idShow = 2) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
18:55:30 T:1778686744   DEBUG: DatabaseUtils::GetSortFieldList: unknown field 25
18:55:30 T:1778686744   DEBUG: bool CMusicDatabase::GetAlbumsByWhere(const CStdString&, const CDatabase::Filter&, CFileItemList&, const SortDescription&, bool) query: SELECT albumview.* FROM albumview  WHERE albumview.strAlbum <> ''
18:55:30 T:1778686744   DEBUG: bool CMusicDatabase::GetAlbumsByWhere(const CStdString&, const CDatabase::Filter&, CFileItemList&, const SortDescription&, bool) - query took 0 ms
18:55:30 T:1778686744   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 2 ms for 0 items query: select * from musicvideoview
18:55:30 T:1778686744   DEBUG: Skin Widgets: Total time needed to request recommended queries: 0:00:00.447266
18:55:30 T:1778686744   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 30 ms for 67 items query: select * from movieview  WHERE ((movieview.playCount IS NULL OR movieview.playCount = 0))
18:55:31 T:1920227320   DEBUG: LocalizeStrings: no strings.po file exist at /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/resources/language/German, fallback to strings.xml
18:55:31 T:1920227320   DEBUG: LocalizeStrings: no strings.po file exist at /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/resources/language/English, fallback to strings.xml
18:55:31 T:1747504680   DEBUG: bool XFILE::CPluginDirectory::WaitOnScriptResult(const CStdString&, int, const CStdString&, bool)- plugin returned successfully
18:55:31 T:1920227320    INFO: CPythonInvoker(3, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): script successfully run
18:55:31 T:1920905872  NOTICE: Thread BackgroundLoader start, auto delete: false
18:55:31 T:1747504680   DEBUG: Unfocus WindowID: 10025, ControlID: 50
18:55:31 T:1920227320    INFO: Python script stopped
18:55:31 T:1920227320   DEBUG: Thread LanguageInvoker 1920227320 terminating
18:55:31 T:1920905872   DEBUG: Thread BackgroundLoader 1920905872 terminating
18:55:31 T:1778686744   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 9 ms for 58 items query: select * from episodeview  WHERE ((episodeview.playCount IS NULL OR episodeview.playCount < 1))
18:55:32 T:1778686744   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 2 ms for 0 items query: select * from musicvideoview
18:55:32 T:1778686744   DEBUG: bool CMusicDatabase::GetAlbumsByWhere(const CStdString&, const CDatabase::Filter&, CFileItemList&, const SortDescription&, bool) query: SELECT albumview.* FROM albumview  WHERE albumview.strAlbum <> ''
18:55:32 T:1778686744   DEBUG: bool CMusicDatabase::GetAlbumsByWhere(const CStdString&, const CDatabase::Filter&, CFileItemList&, const SortDescription&, bool) - query took 1 ms
18:55:32 T:1778686744   DEBUG: Skin Widgets: Total time needed to request recent items queries: 0:00:01.198428
18:55:32 T:1778686744   DEBUG: Skin Widgets: Total time needed for all queries: 0:00:03.742835
18:55:32 T:1787790840   DEBUG: bool CDVDDemuxFFmpeg::Open(CDVDInputStream*) - avformat_find_stream_info starting
18:55:32 T:1787790840   DEBUG: bool CDVDDemuxFFmpeg::Open(CDVDInputStream*) - av_find_stream_info finished
18:55:32 T:1787790840   DEBUG: CSmbFile::Close closing fd 10000
18:55:33 T:1747504680   DEBUG: bool CApplication::ProcessMouse(): trying mouse action leftclick
18:55:33 T:1747504680   DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.ardmediathek_de/?mode=listShowsAZMain&url)
18:55:33 T:1747504680   DEBUG:   ParentPath = [plugin://plugin.video.ardmediathek_de/]
18:55:33 T:1784921144   DEBUG: bool XFILE::CPluginDirectory::StartScript(const CStdString&, bool) - calling plugin ARD Mediathek('plugin://plugin.video.ardmediathek_de/','2','?mode=listShowsAZMain&url')
18:55:33 T:1784921144   DEBUG: bool XFILE::CPluginDirectory::WaitOnScriptResult(const CStdString&, int, const CStdString&, bool) - waiting on the ARD Mediathek (id=4) plugin...
18:55:33 T:1927799944  NOTICE: Thread LanguageInvoker start, auto delete: false
18:55:33 T:1927799944    INFO: initializing python engine.
18:55:33 T:1927799944   DEBUG: CPythonInvoker(4, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): start processing
18:55:33 T:1927799944  NOTICE: -->Python Interpreter Initialized<--
18:55:33 T:1927799944   DEBUG: CPythonInvoker(4, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): the source file to load is "/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py"
18:55:33 T:1927799944   DEBUG: CPythonInvoker(4, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): setting the Python path to /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.pyamf/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.simplejson/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.beautifulsoup4/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/script.module.pil/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.requests/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.mechanize/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/xbmc.debug/:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.xbmcswift2/lib:/:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python26.zip:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/plat-linux3:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-tk:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-old:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-dynload
18:55:33 T:1927799944   DEBUG: CPythonInvoker(4, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): entering source directory /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de
18:55:33 T:1927799944   DEBUG: CPythonInvoker(4, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): instantiating addon using automatically obtained id of "plugin.video.ardmediathek_de" dependent on version 2.1.0 of the xbmc.python api
18:55:33 T:1784921144   DEBUG: bool XFILE::CPluginDirectory::WaitOnScriptResult(const CStdString&, int, const CStdString&, bool)- plugin returned successfully
18:55:33 T:1927799944    INFO: CPythonInvoker(4, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): script successfully run
18:55:33 T:1927799944    INFO: Python script stopped
18:55:33 T:1927799944   DEBUG: Thread LanguageInvoker 1927799944 terminating
18:55:33 T:1921216168  NOTICE: Thread BackgroundLoader start, auto delete: false
18:55:33 T:1747504680   DEBUG: Unfocus WindowID: 10025, ControlID: 50
18:55:33 T:1921216168   DEBUG: Thread BackgroundLoader 1921216168 terminating
18:55:36 T:1747504680   DEBUG: Keyboard: scancode: 0x13, sym: 0x0111, unicode: 0x0000, modifier: 0x0
18:55:36 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): up (0xf080) pressed, action is Up
18:55:36 T:1747504680   DEBUG: Keyboard: scancode: 0x13, sym: 0x0111, unicode: 0x0000, modifier: 0x0
18:55:36 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): up (0xf080) pressed, action is Up
18:55:37 T:1747504680   DEBUG: Keyboard: scancode: 0x13, sym: 0x0111, unicode: 0x0000, modifier: 0x0
18:55:37 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): up (0xf080) pressed, action is Up
18:55:37 T:1747504680   DEBUG: Keyboard: scancode: 0x13, sym: 0x0111, unicode: 0x0000, modifier: 0x0
18:55:37 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): up (0xf080) pressed, action is Up
18:55:37 T:1747504680   DEBUG: Keyboard: scancode: 0x13, sym: 0x0111, unicode: 0x0000, modifier: 0x0
18:55:37 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): up (0xf080) pressed, action is Up
18:55:37 T:1747504680   DEBUG: Keyboard: scancode: 0x13, sym: 0x0111, unicode: 0x0000, modifier: 0x0
18:55:37 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): up (0xf080) pressed, action is Up
18:55:38 T:1747504680   DEBUG: Keyboard: scancode: 0x13, sym: 0x0111, unicode: 0x0000, modifier: 0x0
18:55:38 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): up (0xf080) pressed, action is Up
18:55:38 T:1747504680   DEBUG: Keyboard: scancode: 0x13, sym: 0x0111, unicode: 0x0000, modifier: 0x0
18:55:38 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): up (0xf080) pressed, action is Up
18:55:39 T:1747504680   DEBUG: Keyboard: scancode: 0x42, sym: 0x000d, unicode: 0x000a, modifier: 0x0
18:55:39 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): return (0xf00d) pressed, action is Select
18:55:39 T:1747504680   DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.ardmediathek_de/?mode=listShowsAZ&url=T)
18:55:39 T:1747504680   DEBUG:   ParentPath = [plugin://plugin.video.ardmediathek_de/?mode=listShowsAZMain&url]
18:55:39 T:1785127008   DEBUG: bool XFILE::CPluginDirectory::StartScript(const CStdString&, bool) - calling plugin ARD Mediathek('plugin://plugin.video.ardmediathek_de/','3','?mode=listShowsAZ&url=T')
18:55:39 T:1785127008   DEBUG: bool XFILE::CPluginDirectory::WaitOnScriptResult(const CStdString&, int, const CStdString&, bool) - waiting on the ARD Mediathek (id=5) plugin...
18:55:39 T:1921216168  NOTICE: Thread LanguageInvoker start, auto delete: false
18:55:39 T:1921216168    INFO: initializing python engine.
18:55:39 T:1921216168   DEBUG: CPythonInvoker(5, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): start processing
18:55:39 T:1921216168  NOTICE: -->Python Interpreter Initialized<--
18:55:39 T:1921216168   DEBUG: CPythonInvoker(5, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): the source file to load is "/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py"
18:55:39 T:1921216168   DEBUG: CPythonInvoker(5, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): setting the Python path to /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.pyamf/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.simplejson/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.beautifulsoup4/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/script.module.pil/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.requests/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.mechanize/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/xbmc.debug/:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.xbmcswift2/lib:/:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python26.zip:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/plat-linux3:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-tk:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-old:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-dynload
18:55:39 T:1921216168   DEBUG: CPythonInvoker(5, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): entering source directory /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de
18:55:39 T:1921216168   DEBUG: CPythonInvoker(5, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): instantiating addon using automatically obtained id of "plugin.video.ardmediathek_de" dependent on version 2.1.0 of the xbmc.python api
18:55:39 T:1747504680   DEBUG: ------ Window Init (DialogBusy.xml) ------
18:55:39 T:1747504680   DEBUG: Window DialogBusy.xml was already loaded
18:55:39 T:1747504680   DEBUG: Alloc resources: 0.01m
18:55:40 T:1785127008   DEBUG: bool XFILE::CPluginDirectory::WaitOnScriptResult(const CStdString&, int, const CStdString&, bool)- plugin returned successfully
18:55:40 T:1921216168    INFO: CPythonInvoker(5, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): script successfully run
18:55:40 T:1921216168    INFO: Python script stopped
18:55:40 T:1921216168   DEBUG: Thread LanguageInvoker 1921216168 terminating
18:55:40 T:1907447960  NOTICE: Thread BackgroundLoader start, auto delete: false
18:55:40 T:1907447960   DEBUG: Thread BackgroundLoader 1907447960 terminating
18:55:40 T:1747504680   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
18:55:40 T:1787790840   DEBUG: virtual bool CImageLoader::DoWork() - took 227 ms to load special://masterprofile/Thumbnails/9/9c8c2cf6.jpg
18:55:42 T:1747504680   DEBUG: Keyboard: scancode: 0x13, sym: 0x0111, unicode: 0x0000, modifier: 0x0
18:55:42 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): up (0xf080) pressed, action is Up
18:55:43 T:1747504680   DEBUG: Keyboard: scancode: 0x42, sym: 0x000d, unicode: 0x000a, modifier: 0x0
18:55:43 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): return (0xf00d) pressed, action is Select
18:55:43 T:1747504680   DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.ardmediathek_de/?hideshowname=True&mode=listVideosRss&name=Typisch!&nextpage=True&showName=Typisch!&url=http%3a%2f%2fwww.ardmediathek.de%2ftv%2fTypisch%2fSendung%3fdocumentId%3d14049256%26bcastId%3d14049256%26m23644322%3dquelle.tv%26rss%3dtrue)
18:55:43 T:1747504680   DEBUG:   ParentPath = [plugin://plugin.video.ardmediathek_de/?mode=listShowsAZ&url=T]
18:55:43 T:1787790840   DEBUG: bool XFILE::CPluginDirectory::StartScript(const CStdString&, bool) - calling plugin ARD Mediathek('plugin://plugin.video.ardmediathek_de/','4','?hideshowname=True&mode=listVideosRss&name=Typisch!&nextpage=True&showName=Typisch!&url=http%3a%2f%2fwww.ardmediathek.de%2ftv%2fTypisch%2fSendung%3fdocumentId%3d14049256%26bcastId%3d14049256%26m23644322%3dquelle.tv%26rss%3dtrue')
18:55:43 T:1787790840   DEBUG: bool XFILE::CPluginDirectory::WaitOnScriptResult(const CStdString&, int, const CStdString&, bool) - waiting on the ARD Mediathek (id=6) plugin...
18:55:43 T:1920313936  NOTICE: Thread LanguageInvoker start, auto delete: false
18:55:43 T:1920313936    INFO: initializing python engine.
18:55:43 T:1920313936   DEBUG: CPythonInvoker(6, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): start processing
18:55:43 T:1920313936  NOTICE: -->Python Interpreter Initialized<--
18:55:43 T:1920313936   DEBUG: CPythonInvoker(6, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): the source file to load is "/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py"
18:55:43 T:1920313936   DEBUG: CPythonInvoker(6, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): setting the Python path to /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.pyamf/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.simplejson/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.beautifulsoup4/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/script.module.pil/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.requests/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.mechanize/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/xbmc.debug/:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.xbmcswift2/lib:/:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python26.zip:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/plat-linux3:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-tk:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-old:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-dynload
18:55:43 T:1920313936   DEBUG: CPythonInvoker(6, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): entering source directory /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de
18:55:43 T:1920313936   DEBUG: CPythonInvoker(6, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): instantiating addon using automatically obtained id of "plugin.video.ardmediathek_de" dependent on version 2.1.0 of the xbmc.python api
18:55:44 T:1920313936  NOTICE: get: http://www.ardmediathek.de/tv/Typisch/Sendung?documentId=14049256&bcastId=14049256&m23644322=quelle.tv&rss=true
18:55:44 T:1747504680   DEBUG: ------ Window Init (DialogBusy.xml) ------
18:55:44 T:1747504680   DEBUG: Window DialogBusy.xml was already loaded
18:55:44 T:1747504680   DEBUG: Alloc resources: 0.05m
18:55:44 T:1787790840   DEBUG: bool XFILE::CPluginDirectory::WaitOnScriptResult(const CStdString&, int, const CStdString&, bool)- plugin returned successfully
18:55:44 T:1920313936    INFO: CPythonInvoker(6, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): script successfully run
18:55:44 T:1920313936    INFO: Python script stopped
18:55:44 T:1920313936   DEBUG: Thread LanguageInvoker 1920313936 terminating
18:55:44 T:1747504680   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 1 ms for 3 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=1
18:55:44 T:1920657896  NOTICE: Thread BackgroundLoader start, auto delete: false
18:55:44 T:1747504680   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
18:55:45 T:1920657896   DEBUG: Thread BackgroundLoader 1920657896 terminating
18:55:46 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:55:46 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
18:55:47 T:1747504680   DEBUG: Keyboard: scancode: 0x42, sym: 0x000d, unicode: 0x000a, modifier: 0x0
18:55:47 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): return (0xf00d) pressed, action is Select
18:55:47 T:1747504680    INFO: Loading skin file: DialogContextMenu.xml, load type: KEEP_IN_MEMORY
18:55:47 T:1747504680   DEBUG: Load DialogContextMenu.xml: 2.79ms
18:55:47 T:1747504680   DEBUG: Alloc resources: 3.50ms  (3.02 ms skin load)
18:55:47 T:1747504680   DEBUG: ------ Window Init (DialogContextMenu.xml) ------
18:55:47 T:1747504680   DEBUG: Window DialogContextMenu.xml was already loaded
18:55:47 T:1747504680   DEBUG: Alloc resources: 0.04m
18:55:48 T:1747504680   DEBUG: Keyboard: scancode: 0x42, sym: 0x000d, unicode: 0x000a, modifier: 0x0
18:55:48 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): return (0xf00d) pressed, action is Select
18:55:49 T:1747504680   DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------
18:55:49 T:1747504680   DEBUG: virtual bool CGUIWindowVideoBase::OnPlayMedia(int) plugin://plugin.video.ardmediathek_de/?url=http%3A%2F%2Fwww.ardmediathek.de%2Ftv%2FTypisch%2FDer-G%25C3%25A4rtner-von-Weissenhaus%2FNDR-Fernsehen%2FVideo%3FdocumentId%3D24569848%26amp%3BbcastId%3D14049256&mode=playVideoUrl
18:55:49 T:1747504680   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
18:55:49 T:1747504680   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
18:55:49 T:1747504680   DEBUG: int CVideoDatabase::GetMovieId(const CStdString&) (plugin://plugin.video.ardmediathek_de/?url=http%3A%2F%2Fwww.ardmediathek.de%2Ftv%2FTypisch%2FDer-G%25C3%25A4rtner-von-Weissenhaus%2FNDR-Fernsehen%2FVideo%3FdocumentId%3D24569848%26amp%3BbcastId%3D14049256&mode=playVideoUrl), query = select idMovie from movie where idFile=180
18:55:49 T:1747504680   DEBUG: int CVideoDatabase::GetEpisodeId(const CStdString&, int, int) (plugin://plugin.video.ardmediathek_de/?url=http%3A%2F%2Fwww.ardmediathek.de%2Ftv%2FTypisch%2FDer-G%25C3%25A4rtner-von-Weissenhaus%2FNDR-Fernsehen%2FVideo%3FdocumentId%3D24569848%26amp%3BbcastId%3D14049256&mode=playVideoUrl), query = select idEpisode from episode where idFile=180
18:55:49 T:1747504680   DEBUG: int CVideoDatabase::GetMusicVideoId(const CStdString&) (plugin://plugin.video.ardmediathek_de/?url=http%3A%2F%2Fwww.ardmediathek.de%2Ftv%2FTypisch%2FDer-G%25C3%25A4rtner-von-Weissenhaus%2FNDR-Fernsehen%2FVideo%3FdocumentId%3D24569848%26amp%3BbcastId%3D14049256&mode=playVideoUrl), query = select idMVideo from musicvideo where idFile=180
18:55:49 T:1747504680   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
18:55:49 T:1747504680   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
18:55:49 T:1747504680   DEBUG: bool XFILE::CPluginDirectory::StartScript(const CStdString&, bool) - calling plugin ARD Mediathek('plugin://plugin.video.ardmediathek_de/','5','?url=http%3A%2F%2Fwww.ardmediathek.de%2Ftv%2FTypisch%2FDer-G%25C3%25A4rtner-von-Weissenhaus%2FNDR-Fernsehen%2FVideo%3FdocumentId%3D24569848%26amp%3BbcastId%3D14049256&mode=playVideoUrl')
18:55:49 T:1747504680   DEBUG: bool XFILE::CPluginDirectory::WaitOnScriptResult(const CStdString&, int, const CStdString&, bool) - waiting on the ARD Mediathek (id=7) plugin...
18:55:49 T:1779858512  NOTICE: Thread LanguageInvoker start, auto delete: false
18:55:49 T:1779858512    INFO: initializing python engine.
18:55:49 T:1779858512   DEBUG: CPythonInvoker(7, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): start processing
18:55:49 T:1779858512  NOTICE: -->Python Interpreter Initialized<--
18:55:49 T:1779858512   DEBUG: CPythonInvoker(7, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): the source file to load is "/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py"
18:55:49 T:1779858512   DEBUG: CPythonInvoker(7, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): setting the Python path to /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.pyamf/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.simplejson/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.beautifulsoup4/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/script.module.pil/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.requests/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.mechanize/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/xbmc.debug/:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.xbmcswift2/lib:/:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python26.zip:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/plat-linux3:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-tk:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-old:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-dynload
18:55:49 T:1779858512   DEBUG: CPythonInvoker(7, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): entering source directory /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de
18:55:49 T:1779858512   DEBUG: CPythonInvoker(7, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): instantiating addon using automatically obtained id of "plugin.video.ardmediathek_de" dependent on version 2.1.0 of the xbmc.python api
18:55:49 T:1779858512  NOTICE: get: http://www.ardmediathek.de/tv/Typisch/Der-G%C3%A4rtner-von-Weissenhaus/NDR-Fernsehen/Video?documentId=24569848&amp;bcastId=14049256
18:55:49 T:1779858512  NOTICE: get: http://www.ardmediathek.de/play/media/24569848?devicetype=pc&features=flash
18:55:50 T:1779858512    INFO: CPythonInvoker(7, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): script successfully run
18:55:50 T:1747504680   DEBUG: bool XFILE::CPluginDirectory::WaitOnScriptResult(const CStdString&, int, const CStdString&, bool)- plugin returned successfully
18:55:50 T:1747504680    INFO: void XCURL::DllLibCurlGlobal::easy_aquire(const char*, const char*, XCURL::CURL_HANDLE**, XCURL::CURLM**) - Created session to http://media.ndr.de
18:55:50 T:1779858512    INFO: Python script stopped
18:55:50 T:1779858512   DEBUG: Thread LanguageInvoker 1779858512 terminating
18:55:50 T:1747504680   DEBUG: CCurlFile::GetMimeType - http://media.ndr.de/progressive/2015/0325/TV-20150325-2159-4442.hq.mp4 -> video/mp4
18:55:50 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers(http://media.ndr.de/progressive/2015/0325/TV-20150325-2159-4442.hq.mp4)
18:55:50 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
18:55:50 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
18:55:50 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
18:55:50 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
18:55:50 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
18:55:50 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
18:55:50 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
18:55:50 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
18:55:50 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams
18:55:50 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp
18:55:50 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2
18:55:50 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
18:55:50 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
18:55:50 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
18:55:50 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
18:55:50 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
18:55:50 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
18:55:50 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
18:55:50 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
18:55:50 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
18:55:50 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
18:55:50 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
18:55:50 T:1747504680  NOTICE: DVDPlayer: Opening: http://media.ndr.de/progressive/2015/0325/TV-20150325-2159-4442.hq.mp4
18:55:50 T:1747504680 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
18:55:50 T:1747504680   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
18:55:50 T:1747504680   DEBUG: LinuxRendererGL: Cleaning up GL resources
18:55:50 T:1779858512  NOTICE: Thread DVDPlayer start, auto delete: false
18:55:50 T:1779858512  NOTICE: Creating InputStream
18:55:50 T:1779858512   DEBUG: CFileCache::Open - opening <progressive/2015/0325/TV-20150325-2159-4442.hq.mp4> using cache
18:55:50 T:1779858512   DEBUG: CurlFile::Open(0x72debd70) http://media.ndr.de/progressive/2015/0325/TV-20150325-2159-4442.hq.mp4
18:55:50 T:1917629816  NOTICE: Thread FileCache start, auto delete: false
18:55:50 T:1779858512  NOTICE: Creating Demuxer
18:55:50 T:1779858512   DEBUG: bool CDVDDemuxFFmpeg::Open(CDVDInputStream*) - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
18:55:50 T:1747504680   DEBUG: ------ Window Init (DialogBusy.xml) ------
18:55:50 T:1747504680   DEBUG: Window DialogBusy.xml was already loaded
18:55:50 T:1747504680   DEBUG: Alloc resources: 0.17m
18:55:51 T:1779858512   DEBUG: bool CDVDDemuxFFmpeg::Open(CDVDInputStream*) - avformat_find_stream_info starting
18:55:51 T:1779858512   DEBUG: bool CDVDDemuxFFmpeg::Open(CDVDInputStream*) - av_find_stream_info finished
18:55:51 T:1779858512   DEBUG: CDVDInputStreamFile::SetReadRate - set cache throttle rate to 380545 bytes per second
18:55:51 T:1779858512  NOTICE: Opening video stream: 0 source: 256
18:55:51 T:1779858512  NOTICE: Creating video codec with codec id: 28
18:55:51 T:1779858512   DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no AMCodec:yes MediaCodec:yes OpenMax:no libstagefright:yes VDPAU:no VAAPI:no iMXVPU:no
18:55:51 T:1779858512   DEBUG: FactoryCodec - Video: amcodec - Opening
18:55:51 T:1779858512  NOTICE: aml_get_sysfs_str k=/sys/class/amstream/vcodec_profile v=mpeg12:;
                                            mpeg4:;
                                            vc1:progressive, interlace, wmv3;
                                            h264:;
                                            h264_4k2k:;
                                            hevc_unsupport:;
                                            mjpeg:;
                                            real:rmvb,;
                                            Creating video codec with codec id: 28
18:55:51 T:1779858512    INFO: CBitstreamConverter::Open bitstream to annexb init
18:55:51 T:1779858512   DEBUG: SECTION:LoadDLL(libamplayer.so)
18:55:51 T:1779858512   DEBUG: Loading: /system/lib/libamplayer.so
18:55:51 T:1779858512    INFO: DVDVideoCodecAmlogic: Opened Amlogic Codec
18:55:51 T:1779858512   DEBUG: FactoryCodec - Video: am-h264 - Opened
18:55:51 T:1779858512  NOTICE: Creating video thread
18:55:51 T:1921209752  NOTICE: Thread DVDPlayerVideo start, auto delete: false
18:55:51 T:1779858512  NOTICE: Opening audio stream: 1 source: 256
18:55:51 T:1779858512  NOTICE: Finding audio codec for: 86018
18:55:51 T:1779858512   DEBUG: FactoryCodec - Audio: passthrough - Opening
18:55:51 T:1921209752  NOTICE: running thread: video_thread
18:55:51 T:1779858512   DEBUG: FactoryCodec - Audio: passthrough - Failed
18:55:51 T:1779858512   DEBUG: FactoryCodec - Audio: FFmpeg - Opening
18:55:51 T:1921209752   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
18:55:51 T:1779858512   DEBUG: FactoryCodec - Audio: FFmpeg - Opened
18:55:51 T:1779858512  NOTICE: Creating audio thread
18:55:51 T:1920269424  NOTICE: Thread DVDPlayerAudio start, auto delete: false
18:55:51 T:1779858512   DEBUG: virtual void CDVDPlayer::Process() - Start position set to last stopped position: 1390000
18:55:51 T:1779858512   DEBUG: virtual bool CDVDDemuxFFmpeg::SeekTime(int, bool, double*) - unknown position after seek
18:55:51 T:1779858512   DEBUG: virtual void CDVDPlayer::Process() - starting demuxer from: 1390000
18:55:51 T:1920269424  NOTICE: running thread: CDVDPlayerAudio::Process()
18:55:51 T:1779858512   DEBUG: virtual void CApplication::OnPlayBackStarted() : play state was 1, starting 1
18:55:51 T:1779858512   DEBUG: CDVDPlayer::SetCaching - caching state 1
18:55:51 T:1779858512   DEBUG: CDVDPlayer::SetCaching - caching state 3
18:55:51 T:1921209752   DEBUG: CAMLCodec::SetSpeed, speed(0)
18:55:51 T:1917629816   DEBUG: Previous line repeats 1 times.
18:55:51 T:1917629816    INFO: void XCURL::DllLibCurlGlobal::easy_aquire(const char*, const char*, XCURL::CURL_HANDLE**, XCURL::CURLM**) - Created session to http://media.ndr.de
18:55:51 T:1917629816   DEBUG: CurlFile::CReadState::Connect - Resume from position 344527157
18:55:51 T:1747504680   DEBUG: PlayBackRet CApplication::PlayFile(const CFileItem&, bool) : OpenFile succeed, play state 2
18:55:51 T:1747504680   DEBUG: virtual void CApplication::OnPlayBackStarted() : play state was 2, starting 0
18:55:51 T:1747504680   DEBUG: CGUIInfoManager::SetCurrentMovie(http://media.ndr.de/progressive/2015/0325/TV-20150325-2159-4442.hq.mp4)
18:55:51 T:1747504680   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
18:55:51 T:1747504680   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
18:55:51 T:1747504680   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
18:55:51 T:1921209752   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(1390040000.000000, 1)
18:55:51 T:1921209752    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:55:51 T:1921209752   DEBUG: CAMLCodec::OpenDecoder, android version 19
18:55:51 T:1921209752   DEBUG: codecid_to_vformat, id(28) -> vformat(2)
18:55:51 T:1921209752   DEBUG: codec_tag_to_vdec_type, codec_tag(828601953) -> vdec_type(4)
18:55:51 T:1921209752   DEBUG: CAMLCodec::OpenDecoder hints.width(960), hints.height(540), hints.codec(28), hints.codec_tag(828601953), hints.pid(1)
18:55:51 T:1921209752   DEBUG: CAMLCodec::OpenDecoder hints.fpsrate(25), hints.fpsscale(1), hints.rfpsrate(25), hints.rfpsscale(1), video_rate(3840)
18:55:51 T:1921209752   DEBUG: CAMLCodec::OpenDecoder hints.aspect(1.777778), video_ratio.num(1), video_ratio.den(1)
18:55:51 T:1921209752   DEBUG: CAMLCodec::OpenDecoder hints.orientation(0), hints.forced_aspect(0), hints.extrasize(52)
18:55:51 T:1920269424   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(1390058666.666667, 0)
18:55:51 T:1920269424  NOTICE: Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through)
18:55:51 T:1920269424   DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
18:55:51 T:1738722768    INFO: CActiveAESink::OpenSink - initialize sink
18:55:51 T:1738722768   DEBUG: CActiveAESink::OpenSink - trying to open device AUDIOTRACK:AudioTrack
18:55:51 T:1921209752  NOTICE: aml_get_sysfs_str k=/sys/class/display/mode v=720p
18:55:51 T:1921209752   DEBUG: CAMLCodec::SetSpeed, speed(1000)
18:55:51 T:1921209032  NOTICE: Thread CAMLCodec start, auto delete: false
18:55:51 T:1921209032   DEBUG: CAMLCodec::Process Started
18:55:51 T:1738722768   DEBUG: CActiveAESink::OpenSink - AUDIOTRACK Initialized:
18:55:51 T:1738722768   DEBUG:   Output Device : android
18:55:51 T:1738722768   DEBUG:   Sample Rate   : 48000
18:55:51 T:1738722768   DEBUG:   Sample Format : AE_FMT_S16NE
18:55:51 T:1738722768   DEBUG:   Channel Count : 2
18:55:51 T:1738722768   DEBUG:   Channel Layout: FL,FR
18:55:51 T:1738722768   DEBUG:   Frames        : 2048
18:55:51 T:1738722768   DEBUG:   Frame Samples : 4096
18:55:51 T:1738722768   DEBUG:   Frame Size    : 4
18:55:51 T:1921209752 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:55:51 T:1738905240   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
18:55:51 T:1779858512   DEBUG: Previous line repeats 1 times.
18:55:51 T:1779858512   DEBUG: CDVDPlayer::HandleMessages - player started 1
18:55:51 T:1921209752 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:55:51 T:1921209752  NOTICE:  fps: 25.000000, pwidth: 960, pheight: 540, dwidth: 960, dheight: 540
18:55:51 T:1921209752   DEBUG: int CDVDPlayerVideo::OutputPicture(const DVDVideoPicture*, double) - change configuration. 960x540. framerate: 25.00. format: BYPASS
18:55:51 T:1921209752  NOTICE: Display resolution DESKTOP : 1280x720 @ 60.00 - Full Screen (16)
18:55:51 T:1921209752   DEBUG: CXBMCRenderManager::Configure - 3
18:55:51 T:1747504680   DEBUG: Activating window ID: 12005
18:55:51 T:1747504680   DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
18:55:51 T:1747504680   DEBUG: ------ Window Init (VideoFullScreen.xml) ------
18:55:51 T:1747504680    INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
18:55:51 T:1747504680   DEBUG: Load VideoFullScreen.xml: 32.10ms
18:55:51 T:1747504680   DEBUG: Alloc resources: 33.10ms  (32.32 ms skin load)
18:55:51 T:1747504680  NOTICE: Using GL_TEXTURE_2D
18:55:51 T:1747504680   DEBUG: GL: Requested render method: 0
18:55:51 T:1747504680  NOTICE: GL: Using BYPASS render method
18:55:51 T:1747504680  NOTICE: GL: NPOT texture support detected
18:55:51 T:1747504680   DEBUG: CLinuxRendererGLES: Reorder drawpoints due to method change from 1 to 256
18:55:51 T:1779858512   DEBUG: CDVDPlayer::HandleMessages - player started 2
18:55:51 T:1779858512   DEBUG: CDVDPlayer::SetCaching - caching state 0
18:55:51 T:1921209752   DEBUG: CAMLCodec::SetSpeed, speed(1000)
18:55:51 T:1920269424   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:1390041415.000000, should be:1389896862.344936, error:-144552.655064
18:55:51 T:1747504680   DEBUG: CAMLCodec::SetVideoRect:display(0,0,1280,720)
18:55:51 T:1747504680   DEBUG: CAMLCodec::SetVideoRect:gui(0,0,1920,1080)
18:55:51 T:1747504680   DEBUG: CAMLCodec::SetVideoRect:dst_rect(0,0,1280,720)
18:55:51 T:1747504680   DEBUG: CAMLCodec::SetVideoRect:m_stereo_mode(0)
18:55:51 T:1747504680   DEBUG: CAMLCodec::SetVideoRect:m_stereo_view(0)
18:55:51 T:1747504680  NOTICE: aml_set_sysfs_int k=/sys/class/video/axis v=0 0 1279 719
18:55:52 T:1920269424   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:1390203321.344936, should be:1390080000.000000, error:-123321.344936
18:55:54 T:1920269424   DEBUG: CDVDPlayerAudio:: Discontinuity2 - was:1392107586.000000, should be:1392053586.416363, error:-53999.583637
18:55:56 T:1920269424   DEBUG: CDVDPlayerAudio:: Discontinuity2 - was:1394085422.416363, should be:1394069979.476706, error:-15442.939657
18:55:57 T:1747504680    INFO: void XCURL::DllLibCurlGlobal::CheckIdle() - Closing session to http://feeds.xbmc.org (easy=0x70d4c008, multi=0x6f3fe4e0)
18:56:08 T:1921209752 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:56:14 T:1785127008 WARNING: Previous line repeats 33 times.
18:56:14 T:1785127008   DEBUG: Thread JobWorker 1785127008 terminating (autodelete)
18:56:14 T:1784921144   DEBUG: Thread JobWorker 1784921144 terminating (autodelete)
18:56:14 T:1787790840   DEBUG: Thread JobWorker 1787790840 terminating (autodelete)
18:56:14 T:1787824352   DEBUG: Thread JobWorker 1787824352 terminating (autodelete)
18:56:15 T:1921209752 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:57:10 T:1747504680 WARNING: Previous line repeats 274 times.
18:57:10 T:1747504680  NOTICE: Samba is idle. Closing the remaining connections
18:57:10 T:1921209752 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:00:28 T:1917629816 WARNING: Previous line repeats 979 times.
19:00:28 T:1917629816    INFO: CFileCache::Process - Hit eof.
19:00:29 T:1921209752 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:01:12 T:1921209752 WARNING: Previous line repeats 212 times.
19:01:12 T:1921209752    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:01:20 T:1920269424   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_EOF
19:01:20 T:1920269424 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:01:20 T:1779858512    INFO: virtual void CDVDPlayer::Process() - eof reading from demuxer
19:01:20 T:1779858512  NOTICE: CDVDPlayer::OnExit()
19:01:20 T:1779858512  NOTICE: DVDPlayer: eof, waiting for queues to empty
19:01:20 T:1779858512  NOTICE: DVDPlayer: closing audio stream
19:01:20 T:1779858512  NOTICE: Closing audio stream
19:01:20 T:1779858512  NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty
19:01:20 T:1920269424   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
19:01:20 T:1779858512  NOTICE: Waiting for audio thread to exit
19:01:20 T:1920269424   DEBUG: CDVDPlayerAudio::Process - Abort received, exiting thread
19:01:20 T:1920269424  NOTICE: thread end: CDVDPlayerAudio::OnExit()
19:01:20 T:1779858512  NOTICE: Closing audio device
19:01:20 T:1920269424   DEBUG: Thread DVDPlayerAudio 1920269424 terminating
19:01:20 T:1779858512  NOTICE: Deleting audio codec
19:01:20 T:1738905240   DEBUG: CActiveAE::DiscardStream - audio stream deleted
19:01:20 T:1779858512  NOTICE: DVDPlayer: closing video stream
19:01:20 T:1779858512  NOTICE: Closing video stream
19:01:20 T:1779858512  NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty
19:01:20 T:1738905240   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:01:20 T:1921209752   DEBUG: Previous line repeats 1 times.
19:01:20 T:1921209752   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
19:01:20 T:1921209752   ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
19:01:20 T:1921209752  NOTICE: thread end: video_thread
19:01:20 T:1921209752   DEBUG: Thread DVDPlayerVideo 1921209752 terminating
19:01:20 T:1779858512  NOTICE: waiting for video thread to exit
19:01:20 T:1779858512  NOTICE: deleting video codec
19:01:20 T:1779858512   DEBUG: CAMLCodec::CloseDecoder
19:01:20 T:1921209032   DEBUG: CAMLCodec::Process Stopped
19:01:20 T:1921209032   DEBUG: Thread CAMLCodec 1921209032 terminating
19:01:20 T:1738722768    INFO: CActiveAESink::OpenSink - initialize sink
19:01:20 T:1738722768   DEBUG: CActiveAESink::OpenSink - trying to open device AUDIOTRACK:AudioTrack
19:01:20 T:1738722768   DEBUG: CActiveAESink::OpenSink - AUDIOTRACK Initialized:
19:01:20 T:1738722768   DEBUG:   Output Device : android
19:01:20 T:1738722768   DEBUG:   Sample Rate   : 44100
19:01:20 T:1738722768   DEBUG:   Sample Format : AE_FMT_S16NE
19:01:20 T:1738722768   DEBUG:   Channel Count : 2
19:01:20 T:1738722768   DEBUG:   Channel Layout: FL,FR
19:01:20 T:1738722768   DEBUG:   Frames        : 1881
19:01:20 T:1738722768   DEBUG:   Frame Samples : 3762
19:01:20 T:1738722768   DEBUG:   Frame Size    : 4
19:01:20 T:1738905240   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:01:20 T:1779858512   DEBUG: Previous line repeats 1 times.
19:01:20 T:1779858512  NOTICE: CDVDPlayer::OnExit() deleting demuxer
19:01:20 T:1779858512  NOTICE: CDVDPlayer::OnExit() deleting input stream
19:01:20 T:1917629816   DEBUG: Thread FileCache 1917629816 terminating
19:01:20 T:1779858512   DEBUG: virtual void CApplication::OnPlayBackEnded() : play state was 2, starting 0
19:01:20 T:1779858512   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
19:01:20 T:1779858512   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
19:01:20 T:1779858512   DEBUG: Thread DVDPlayer 1779858512 terminating
19:01:20 T:1747504680   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
19:01:20 T:1747504680   DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
19:01:20 T:1747504680   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
19:01:21 T:1747504680   DEBUG: ------ Window Init (MyVideoNav.xml) ------
19:01:21 T:1747504680   DEBUG: Window MyVideoNav.xml was already loaded
19:01:21 T:1747504680   DEBUG: Alloc resources: 6.40m
19:01:21 T:1747504680   DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.ardmediathek_de/?hideshowname=True&mode=listVideosRss&name=Typisch!&nextpage=True&showName=Typisch!&url=http%3a%2f%2fwww.ardmediathek.de%2ftv%2fTypisch%2fSendung%3fdocumentId%3d14049256%26bcastId%3d14049256%26m23644322%3dquelle.tv%26rss%3dtrue)
19:01:21 T:1747504680   DEBUG:   ParentPath = [plugin://plugin.video.ardmediathek_de/?hideshowname=True&mode=listVideosRss&name=Typisch!&nextpage=True&showName=Typisch!&url=http%3a%2f%2fwww.ardmediathek.de%2ftv%2fTypisch%2fSendung%3fdocumentId%3d14049256%26bcastId%3d14049256%26m23644322%3dquelle.tv%26rss%3dtrue]
19:01:21 T:1747504680   DEBUG: bool XFILE::CPluginDirectory::StartScript(const CStdString&, bool) - calling plugin ARD Mediathek('plugin://plugin.video.ardmediathek_de/','6','?hideshowname=True&mode=listVideosRss&name=Typisch!&nextpage=True&showName=Typisch!&url=http%3a%2f%2fwww.ardmediathek.de%2ftv%2fTypisch%2fSendung%3fdocumentId%3d14049256%26bcastId%3d14049256%26m23644322%3dquelle.tv%26rss%3dtrue')
19:01:21 T:1747504680   DEBUG: bool XFILE::CPluginDirectory::WaitOnScriptResult(const CStdString&, int, const CStdString&, bool) - waiting on the ARD Mediathek (id=8) plugin...
19:01:21 T:1925833200  NOTICE: Thread LanguageInvoker start, auto delete: false
19:01:21 T:1925833200    INFO: initializing python engine.
19:01:21 T:1925833200   DEBUG: CPythonInvoker(8, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): start processing
19:01:21 T:1747504680  NOTICE: CDVDPlayer::CloseFile()
19:01:21 T:1747504680  NOTICE: DVDPlayer: waiting for threads to exit
19:01:21 T:1747504680  NOTICE: DVDPlayer: finished waiting
19:01:21 T:1747504680   DEBUG: LinuxRendererGL: Cleaning up GL resources
19:01:21 T:1747504680  NOTICE: CDVDPlayer::CloseFile()
19:01:21 T:1747504680  NOTICE: DVDPlayer: waiting for threads to exit
19:01:21 T:1747504680  NOTICE: DVDPlayer: finished waiting
19:01:21 T:1747504680   DEBUG: LinuxRendererGL: Cleaning up GL resources
19:01:21 T:1925833200  NOTICE: -->Python Interpreter Initialized<--
19:01:21 T:1925833200   DEBUG: CPythonInvoker(8, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): the source file to load is "/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py"
19:01:21 T:1925833200   DEBUG: CPythonInvoker(8, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): setting the Python path to /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.pyamf/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.simplejson/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.beautifulsoup4/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/script.module.pil/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.requests/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.mechanize/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/xbmc.debug/:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.xbmcswift2/lib:/:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python26.zip:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/plat-linux3:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-tk:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-old:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-dynload
19:01:21 T:1925833200   DEBUG: CPythonInvoker(8, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): entering source directory /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de
19:01:21 T:1925833200   DEBUG: CPythonInvoker(8, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): instantiating addon using automatically obtained id of "plugin.video.ardmediathek_de" dependent on version 2.1.0 of the xbmc.python api
19:01:21 T:1747504680   ERROR: Control 51 in window 10025 has been asked to focus, but it can't
19:01:21 T:1747504680   DEBUG: SECTION:UnloadDelayed(DLL: libImageLib-arm.so)
19:01:21 T:1747504680   DEBUG: Unloading: libImageLib-arm.so
19:01:21 T:1907372448  NOTICE: Thread JobWorker start, auto delete: true
19:01:21 T:1907372448   DEBUG: virtual bool CSaveFileStateJob::DoWork() - Saving file state for video item plugin://plugin.video.ardmediathek_de/?url=http%3A%2F%2Fwww.ardmediathek.de%2Ftv%2FTypisch%2FDer-G%25C3%25A4rtner-von-Weissenhaus%2FNDR-Fernsehen%2FVideo%3FdocumentId%3D24569848%26amp%3BbcastId%3D14049256&mode=playVideoUrl
19:01:21 T:1907372448   DEBUG: virtual bool CSaveFileStateJob::DoWork() - Marking video item plugin://plugin.video.ardmediathek_de/?url=http%3A%2F%2Fwww.ardmediathek.de%2Ftv%2FTypisch%2FDer-G%25C3%25A4rtner-von-Weissenhaus%2FNDR-Fernsehen%2FVideo%3FdocumentId%3D24569848%26amp%3BbcastId%3D14049256&mode=playVideoUrl as watched
19:01:21 T:1925833200  NOTICE: get: http://www.ardmediathek.de/tv/Typisch/Sendung?documentId=14049256&bcastId=14049256&m23644322=quelle.tv&rss=true
19:01:22 T:1747504680   DEBUG: bool XFILE::CPluginDirectory::WaitOnScriptResult(const CStdString&, int, const CStdString&, bool)- plugin returned successfully
19:01:22 T:1925833200    INFO: CPythonInvoker(8, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): script successfully run
19:01:22 T:1747504680   DEBUG: Saving fileitems [plugin://plugin.video.ardmediathek_de/?hideshowname=True&mode=listVideosRss&name=Typisch!&nextpage=True&showName=Typisch!&url=http%3a%2f%2fwww.ardmediathek.de%2ftv%2fTypisch%2fSendung%3fdocumentId%3d14049256%26bcastId%3d14049256%26m23644322%3dquelle.tv%26rss%3dtrue]
19:01:22 T:1747504680   DEBUG:   -- items: 45, sort method: 0, ascending: false
19:01:22 T:1747504680   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 1 ms for 3 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=1
19:01:22 T:1925833200    INFO: Python script stopped
19:01:22 T:1925833200   DEBUG: Thread LanguageInvoker 1925833200 terminating
19:01:22 T:1916471456  NOTICE: Thread BackgroundLoader start, auto delete: false
19:01:22 T:1787854968  NOTICE: Thread JobWorker start, auto delete: true
19:01:22 T:1916471456  NOTICE: Previous line repeats 1 times.
19:01:22 T:1916471456   DEBUG: Thread BackgroundLoader 1916471456 terminating
19:01:26 T:1738905240   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:01:50 T:1747504680   DEBUG: SECTION:UnloadDelayed(DLL: libamplayer.so)
19:01:50 T:1747504680   DEBUG: Unloading: libamplayer.so
19:01:51 T:1747504680    INFO: void XCURL::DllLibCurlGlobal::CheckIdle() - Closing session to http://media.ndr.de (easy=0x72d4b9e0, multi=0x72838488)
19:01:51 T:1747504680    INFO: void XCURL::DllLibCurlGlobal::CheckIdle() - Closing session to http://media.ndr.de (easy=0x72b58008, multi=0x727a3060)
19:01:52 T:1907372448   DEBUG: Thread JobWorker 1907372448 terminating (autodelete)
19:01:52 T:1920671880   DEBUG: Thread JobWorker 1920671880 terminating (autodelete)
19:01:52 T:1787854968   DEBUG: Thread JobWorker 1787854968 terminating (autodelete)
19:02:06 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:02:06 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:02:06 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:02:06 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:02:06 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:02:06 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:02:09 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:02:09 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:02:21 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:02:21 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:02:24 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:02:24 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:02:30 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:02:30 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:02:33 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:02:33 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:02:37 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:02:37 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:02:41 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:02:41 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:02:51 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:02:51 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:02:54 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:02:54 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:02:56 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:02:56 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:02:56 T:1783418256  NOTICE: Thread JobWorker start, auto delete: true
19:02:56 T:1783418256    INFO: void XCURL::DllLibCurlGlobal::easy_aquire(const char*, const char*, XCURL::CURL_HANDLE**, XCURL::CURLM**) - Created session to http://www.ardmediathek.de
19:02:56 T:1783418256   DEBUG: CCurlFile::GetMimeType - http://www.ardmediathek.de/image/00/25/93/42/86/549738937/16x9/448 -> image/jpeg
19:02:56 T:1783418256   DEBUG: CFileCache::Open - opening <image/00/25/93/42/86/549738937/16x9/448> using cache
19:02:56 T:1783418256   DEBUG: CurlFile::Open(0x6f3fa3b8) http://www.ardmediathek.de/image/00/25/93/42/86/549738937/16x9/448
19:02:56 T:1907372448  NOTICE: Thread FileCache start, auto delete: false
19:02:56 T:1907372448    INFO: CFileCache::Process - Hit eof.
19:02:56 T:1907372448   DEBUG: Thread FileCache 1907372448 terminating
19:02:56 T:1783418256   DEBUG: Caching image 'http://www.ardmediathek.de/image/00/25/93/42/86/549738937/16x9/448' to 'd/db6b0292.jpg':
19:02:56 T:1783418256   DEBUG: cached image 'special://masterprofile/Thumbnails/d/db6b0292.jpg' size 448x252
19:03:01 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:03:01 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:03:01 T:1783418256   DEBUG: CCurlFile::GetMimeType - http://www.ardmediathek.de/image/00/26/31/07/10/549738937/16x9/448 -> image/jpeg
19:03:01 T:1783418256   DEBUG: CFileCache::Open - opening <image/00/26/31/07/10/549738937/16x9/448> using cache
19:03:01 T:1783418256   DEBUG: CurlFile::Open(0x72ccf8f0) http://www.ardmediathek.de/image/00/26/31/07/10/549738937/16x9/448
19:03:01 T:1920671880  NOTICE: Thread FileCache start, auto delete: false
19:03:01 T:1920671880    INFO: CFileCache::Process - Hit eof.
19:03:01 T:1920671880   DEBUG: Thread FileCache 1920671880 terminating
19:03:01 T:1783418256   DEBUG: Caching image 'http://www.ardmediathek.de/image/00/26/31/07/10/549738937/16x9/448' to '8/88b256d2.jpg':
19:03:01 T:1783418256   DEBUG: cached image 'special://masterprofile/Thumbnails/8/88b256d2.jpg' size 448x252
19:03:03 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:03:03 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:03:03 T:1783418256   DEBUG: CCurlFile::GetMimeType - http://www.ardmediathek.de/image/00/26/16/85/60/549738937/16x9/448 -> image/jpeg
19:03:03 T:1783418256   DEBUG: CFileCache::Open - opening <image/00/26/16/85/60/549738937/16x9/448> using cache
19:03:03 T:1783418256   DEBUG: CurlFile::Open(0x6a967c18) http://www.ardmediathek.de/image/00/26/16/85/60/549738937/16x9/448
19:03:03 T:1921326368  NOTICE: Thread FileCache start, auto delete: false
19:03:03 T:1921326368    INFO: CFileCache::Process - Hit eof.
19:03:03 T:1921326368   DEBUG: Thread FileCache 1921326368 terminating
19:03:03 T:1783418256   DEBUG: Caching image 'http://www.ardmediathek.de/image/00/26/16/85/60/549738937/16x9/448' to '6/68561cdb.jpg':
19:03:03 T:1783418256   DEBUG: cached image 'special://masterprofile/Thumbnails/6/68561cdb.jpg' size 448x252
19:03:05 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:03:05 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:03:06 T:1783418256   DEBUG: CCurlFile::GetMimeType - http://www.ardmediathek.de/image/00/26/23/73/52/445341358/16x9/448 -> image/jpeg
19:03:06 T:1783418256   DEBUG: CFileCache::Open - opening <image/00/26/23/73/52/445341358/16x9/448> using cache
19:03:06 T:1783418256   DEBUG: CurlFile::Open(0x727adf90) http://www.ardmediathek.de/image/00/26/23/73/52/445341358/16x9/448
19:03:06 T:1920657104  NOTICE: Thread FileCache start, auto delete: false
19:03:06 T:1920657104    INFO: CFileCache::Process - Hit eof.
19:03:06 T:1920657104   DEBUG: Thread FileCache 1920657104 terminating
19:03:06 T:1783418256   DEBUG: Caching image 'http://www.ardmediathek.de/image/00/26/23/73/52/445341358/16x9/448' to 'b/bf3c6e29.jpg':
19:03:06 T:1783418256   DEBUG: cached image 'special://masterprofile/Thumbnails/b/bf3c6e29.jpg' size 448x252
19:03:08 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:03:08 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:03:09 T:1783418256   DEBUG: CCurlFile::GetMimeType - http://www.ardmediathek.de/image/00/23/10/51/26/549738937/16x9/448 -> image/jpeg
19:03:09 T:1783418256   DEBUG: CFileCache::Open - opening <image/00/23/10/51/26/549738937/16x9/448> using cache
19:03:09 T:1783418256   DEBUG: CurlFile::Open(0x72ccf8f0) http://www.ardmediathek.de/image/00/23/10/51/26/549738937/16x9/448
19:03:09 T:1907372448  NOTICE: Thread FileCache start, auto delete: false
19:03:09 T:1907372448    INFO: CFileCache::Process - Hit eof.
19:03:09 T:1907372448   DEBUG: Thread FileCache 1907372448 terminating
19:03:09 T:1783418256   DEBUG: Caching image 'http://www.ardmediathek.de/image/00/23/10/51/26/549738937/16x9/448' to '8/888e19bb.jpg':
19:03:09 T:1783418256   DEBUG: cached image 'special://masterprofile/Thumbnails/8/888e19bb.jpg' size 448x252
19:03:32 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:03:32 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:03:32 T:1783418256   DEBUG: CCurlFile::GetMimeType - http://www.ardmediathek.de/image/00/25/74/03/24/549738937/16x9/448 -> image/jpeg
19:03:32 T:1783418256   DEBUG: CFileCache::Open - opening <image/00/25/74/03/24/549738937/16x9/448> using cache
19:03:32 T:1783418256   DEBUG: CurlFile::Open(0x6a967c18) http://www.ardmediathek.de/image/00/25/74/03/24/549738937/16x9/448
19:03:32 T:1920671880  NOTICE: Thread FileCache start, auto delete: false
19:03:32 T:1920671880    INFO: CFileCache::Process - Hit eof.
19:03:32 T:1920671880   DEBUG: Thread FileCache 1920671880 terminating
19:03:32 T:1783418256   DEBUG: Caching image 'http://www.ardmediathek.de/image/00/25/74/03/24/549738937/16x9/448' to 'a/ad35d0f6.jpg':
19:03:32 T:1783418256   DEBUG: cached image 'special://masterprofile/Thumbnails/a/ad35d0f6.jpg' size 448x252
19:03:35 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:03:35 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:03:35 T:1783418256   DEBUG: CCurlFile::GetMimeType - http://www.ardmediathek.de/image/00/20/42/73/08/549738937/16x9/448 -> image/jpeg
19:03:35 T:1783418256   DEBUG: CFileCache::Open - opening <image/00/20/42/73/08/549738937/16x9/448> using cache
19:03:35 T:1783418256   DEBUG: CurlFile::Open(0x727adf90) http://www.ardmediathek.de/image/00/20/42/73/08/549738937/16x9/448
19:03:35 T:1921326368  NOTICE: Thread FileCache start, auto delete: false
19:03:35 T:1921326368    INFO: CFileCache::Process - Hit eof.
19:03:35 T:1921326368   DEBUG: Thread FileCache 1921326368 terminating
19:03:35 T:1783418256   DEBUG: Caching image 'http://www.ardmediathek.de/image/00/20/42/73/08/549738937/16x9/448' to '6/6365de1d.jpg':
19:03:35 T:1783418256   DEBUG: cached image 'special://masterprofile/Thumbnails/6/6365de1d.jpg' size 448x252
19:03:37 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:03:37 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:03:38 T:1783418256   DEBUG: CCurlFile::GetMimeType - http://www.ardmediathek.de/image/00/25/43/72/28/549738937/16x9/448 -> image/jpeg
19:03:38 T:1783418256   DEBUG: CFileCache::Open - opening <image/00/25/43/72/28/549738937/16x9/448> using cache
19:03:38 T:1783418256   DEBUG: CurlFile::Open(0x72ccf8f0) http://www.ardmediathek.de/image/00/25/43/72/28/549738937/16x9/448
19:03:38 T:1907372448  NOTICE: Thread FileCache start, auto delete: false
19:03:38 T:1907372448    INFO: CFileCache::Process - Hit eof.
19:03:38 T:1907372448   DEBUG: Thread FileCache 1907372448 terminating
19:03:38 T:1783418256   DEBUG: Caching image 'http://www.ardmediathek.de/image/00/25/43/72/28/549738937/16x9/448' to 'a/ad0e9274.jpg':
19:03:38 T:1783418256   DEBUG: cached image 'special://masterprofile/Thumbnails/a/ad0e9274.jpg' size 448x252
19:03:39 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:03:39 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:03:40 T:1783418256   DEBUG: CCurlFile::GetMimeType - http://www.ardmediathek.de/image/00/25/29/53/90/549738937/16x9/448 -> image/jpeg
19:03:40 T:1783418256   DEBUG: CFileCache::Open - opening <image/00/25/29/53/90/549738937/16x9/448> using cache
19:03:40 T:1783418256   DEBUG: CurlFile::Open(0x6f3fa3b8) http://www.ardmediathek.de/image/00/25/29/53/90/549738937/16x9/448
19:03:40 T:1921326368  NOTICE: Thread FileCache start, auto delete: false
19:03:40 T:1921326368    INFO: CFileCache::Process - Hit eof.
19:03:40 T:1921326368   DEBUG: Thread FileCache 1921326368 terminating
19:03:40 T:1783418256   DEBUG: Caching image 'http://www.ardmediathek.de/image/00/25/29/53/90/549738937/16x9/448' to 'f/f808cd78.jpg':
19:03:40 T:1783418256   DEBUG: cached image 'special://masterprofile/Thumbnails/f/f808cd78.jpg' size 448x252
19:03:43 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:03:43 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:03:43 T:1783418256   DEBUG: CCurlFile::GetMimeType - http://www.ardmediathek.de/image/00/24/41/71/14/549738937/16x9/448 -> image/jpeg
19:03:43 T:1783418256   DEBUG: CFileCache::Open - opening <image/00/24/41/71/14/549738937/16x9/448> using cache
19:03:43 T:1783418256   DEBUG: CurlFile::Open(0x72792658) http://www.ardmediathek.de/image/00/24/41/71/14/549738937/16x9/448
19:03:43 T:1920671880  NOTICE: Thread FileCache start, auto delete: false
19:03:44 T:1920671880    INFO: CFileCache::Process - Hit eof.
19:03:44 T:1920671880   DEBUG: Thread FileCache 1920671880 terminating
19:03:44 T:1783418256   DEBUG: Caching image 'http://www.ardmediathek.de/image/00/24/41/71/14/549738937/16x9/448' to '2/2856e3ac.jpg':
19:03:44 T:1783418256   DEBUG: cached image 'special://masterprofile/Thumbnails/2/2856e3ac.jpg' size 448x252
19:03:47 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:03:47 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:03:47 T:1783418256   DEBUG: CCurlFile::GetMimeType - http://www.ardmediathek.de/image/00/18/60/79/26/403701195/16x9/448 -> image/jpeg
19:03:47 T:1783418256   DEBUG: CFileCache::Open - opening <image/00/18/60/79/26/403701195/16x9/448> using cache
19:03:47 T:1783418256   DEBUG: CurlFile::Open(0x6a967c18) http://www.ardmediathek.de/image/00/18/60/79/26/403701195/16x9/448
19:03:47 T:1907372448  NOTICE: Thread FileCache start, auto delete: false
19:03:47 T:1907372448    INFO: CFileCache::Process - Hit eof.
19:03:47 T:1907372448   DEBUG: Thread FileCache 1907372448 terminating
19:03:47 T:1783418256   DEBUG: Caching image 'http://www.ardmediathek.de/image/00/18/60/79/26/403701195/16x9/448' to '4/44239c5c.jpg':
19:03:47 T:1783418256   DEBUG: cached image 'special://masterprofile/Thumbnails/4/44239c5c.jpg' size 448x252
19:03:51 T:1747504680   DEBUG: Keyboard: scancode: 0x14, sym: 0x0112, unicode: 0x0000, modifier: 0x0
19:03:51 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): down (0xf081) pressed, action is Down
19:03:51 T:1783418256   DEBUG: CCurlFile::GetMimeType - http://www.ardmediathek.de/image/00/24/13/07/06/549738937/16x9/448 -> image/jpeg
19:03:51 T:1783418256   DEBUG: CFileCache::Open - opening <image/00/24/13/07/06/549738937/16x9/448> using cache
19:03:51 T:1783418256   DEBUG: CurlFile::Open(0x7274b8f8) http://www.ardmediathek.de/image/00/24/13/07/06/549738937/16x9/448
19:03:51 T:1907372448  NOTICE: Thread FileCache start, auto delete: false
19:03:51 T:1907372448    INFO: CFileCache::Process - Hit eof.
19:03:51 T:1783418256   DEBUG: Caching image 'http://www.ardmediathek.de/image/00/24/13/07/06/549738937/16x9/448' to '7/724ba862.jpg':
19:03:51 T:1907372448   DEBUG: Thread FileCache 1907372448 terminating
19:03:51 T:1783418256   DEBUG: cached image 'special://masterprofile/Thumbnails/7/724ba862.jpg' size 448x252
19:03:58 T:1747504680   DEBUG: Keyboard: scancode: 0x16, sym: 0x0113, unicode: 0x0000, modifier: 0x0
19:03:58 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): right (0xf083) pressed, action is Right
19:04:00 T:1747504680   DEBUG: Keyboard: scancode: 0x15, sym: 0x0114, unicode: 0x0000, modifier: 0x0
19:04:00 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): left (0xf082) pressed, action is Left
19:04:00 T:1747504680   DEBUG: Keyboard: scancode: 0x15, sym: 0x0114, unicode: 0x0000, modifier: 0x0
19:04:00 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): left (0xf082) pressed, action is Left
19:04:05 T:1747504680   DEBUG: Keyboard: scancode: 0x16, sym: 0x0113, unicode: 0x0000, modifier: 0x0
19:04:05 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): right (0xf083) pressed, action is Right
19:04:08 T:1747504680   DEBUG: Keyboard: scancode: 0x42, sym: 0x000d, unicode: 0x000a, modifier: 0x0
19:04:08 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): return (0xf00d) pressed, action is Select
19:04:08 T:1747504680   DEBUG: virtual bool CGUIWindowVideoBase::OnPlayMedia(int) plugin://plugin.video.ardmediathek_de/?url=http%3A%2F%2Fwww.ardmediathek.de%2Ftv%2FTypisch%2FMach-weiter-Elfriede%2FNDR-Fernsehen%2FVideo%3FdocumentId%3D18607920%26amp%3BbcastId%3D14049256&mode=playVideoUrl
19:04:08 T:1747504680   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
19:04:08 T:1747504680   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
19:04:08 T:1747504680   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
19:04:08 T:1747504680   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
19:04:08 T:1747504680   DEBUG: bool XFILE::CPluginDirectory::StartScript(const CStdString&, bool) - calling plugin ARD Mediathek('plugin://plugin.video.ardmediathek_de/','7','?url=http%3A%2F%2Fwww.ardmediathek.de%2Ftv%2FTypisch%2FMach-weiter-Elfriede%2FNDR-Fernsehen%2FVideo%3FdocumentId%3D18607920%26amp%3BbcastId%3D14049256&mode=playVideoUrl')
19:04:08 T:1747504680   DEBUG: bool XFILE::CPluginDirectory::WaitOnScriptResult(const CStdString&, int, const CStdString&, bool) - waiting on the ARD Mediathek (id=9) plugin...
19:04:08 T:1921326368  NOTICE: Thread LanguageInvoker start, auto delete: false
19:04:08 T:1921326368    INFO: initializing python engine.
19:04:08 T:1921326368   DEBUG: CPythonInvoker(9, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): start processing
19:04:08 T:1921326368  NOTICE: -->Python Interpreter Initialized<--
19:04:08 T:1921326368   DEBUG: CPythonInvoker(9, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): the source file to load is "/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py"
19:04:08 T:1921326368   DEBUG: CPythonInvoker(9, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): setting the Python path to /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.pyamf/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.simplejson/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.beautifulsoup4/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/script.module.pil/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.requests/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.mechanize/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/xbmc.debug/:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.xbmcswift2/lib:/:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python26.zip:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/plat-linux3:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-tk:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-old:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-dynload
19:04:08 T:1921326368   DEBUG: CPythonInvoker(9, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): entering source directory /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de
19:04:08 T:1921326368   DEBUG: CPythonInvoker(9, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): instantiating addon using automatically obtained id of "plugin.video.ardmediathek_de" dependent on version 2.1.0 of the xbmc.python api
19:04:08 T:1921326368  NOTICE: get: http://www.ardmediathek.de/tv/Typisch/Mach-weiter-Elfriede/NDR-Fernsehen/Video?documentId=18607920&amp;bcastId=14049256
19:04:09 T:1921326368  NOTICE: get: http://www.ardmediathek.de/play/media/18607920?devicetype=pc&features=flash
19:04:09 T:1921326368    INFO: CPythonInvoker(9, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): script successfully run
19:04:09 T:1747504680   DEBUG: bool XFILE::CPluginDirectory::WaitOnScriptResult(const CStdString&, int, const CStdString&, bool)- plugin returned successfully
19:04:09 T:1747504680    INFO: void XCURL::DllLibCurlGlobal::easy_aquire(const char*, const char*, XCURL::CURL_HANDLE**, XCURL::CURLM**) - Created session to http://media.ndr.de
19:04:09 T:1921326368    INFO: Python script stopped
19:04:09 T:1921326368   DEBUG: Thread LanguageInvoker 1921326368 terminating
19:04:10 T:1747504680   DEBUG: CCurlFile::GetMimeType - http://media.ndr.de/progressive/2013/1212/TV-20131212-1919-0742.hq.mp4 -> video/mp4
19:04:10 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers(http://media.ndr.de/progressive/2013/1212/TV-20131212-1919-0742.hq.mp4)
19:04:10 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
19:04:10 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
19:04:10 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
19:04:10 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
19:04:10 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
19:04:10 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
19:04:10 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
19:04:10 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
19:04:10 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams
19:04:10 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp
19:04:10 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2
19:04:10 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
19:04:10 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
19:04:10 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
19:04:10 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
19:04:10 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
19:04:10 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
19:04:10 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
19:04:10 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
19:04:10 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
19:04:10 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
19:04:10 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
19:04:10 T:1747504680  NOTICE: DVDPlayer: Opening: http://media.ndr.de/progressive/2013/1212/TV-20131212-1919-0742.hq.mp4
19:04:10 T:1747504680 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:04:10 T:1747504680   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
19:04:10 T:1747504680   DEBUG: LinuxRendererGL: Cleaning up GL resources
19:04:10 T:1921326368  NOTICE: Thread DVDPlayer start, auto delete: false
19:04:10 T:1921326368  NOTICE: Creating InputStream
19:04:10 T:1921326368   DEBUG: CFileCache::Open - opening <progressive/2013/1212/TV-20131212-1919-0742.hq.mp4> using cache
19:04:10 T:1921326368   DEBUG: CurlFile::Open(0x7274b8f8) http://media.ndr.de/progressive/2013/1212/TV-20131212-1919-0742.hq.mp4
19:04:10 T:1784921144  NOTICE: Thread FileCache start, auto delete: false
19:04:10 T:1921326368  NOTICE: Creating Demuxer
19:04:10 T:1747504680   DEBUG: ------ Window Init (DialogBusy.xml) ------
19:04:10 T:1747504680   DEBUG: Window DialogBusy.xml was already loaded
19:04:10 T:1747504680   DEBUG: Alloc resources: 0.11m
19:04:10 T:1921326368   DEBUG: bool CDVDDemuxFFmpeg::Open(CDVDInputStream*) - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
19:04:13 T:1921326368   DEBUG: bool CDVDDemuxFFmpeg::Open(CDVDInputStream*) - avformat_find_stream_info starting
19:04:13 T:1921326368   DEBUG: bool CDVDDemuxFFmpeg::Open(CDVDInputStream*) - av_find_stream_info finished
19:04:13 T:1921326368   DEBUG: CDVDInputStreamFile::SetReadRate - set cache throttle rate to 354658 bytes per second
19:04:13 T:1921326368  NOTICE: Opening video stream: 0 source: 256
19:04:13 T:1921326368  NOTICE: Creating video codec with codec id: 28
19:04:13 T:1921326368   DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no AMCodec:yes MediaCodec:yes OpenMax:no libstagefright:yes VDPAU:no VAAPI:no iMXVPU:no
19:04:13 T:1921326368   DEBUG: FactoryCodec - Video: amcodec - Opening
19:04:13 T:1921326368    INFO: CBitstreamConverter::Open bitstream to annexb init
19:04:13 T:1921326368   DEBUG: SECTION:LoadDLL(libamplayer.so)
19:04:13 T:1921326368   DEBUG: Loading: /system/lib/libamplayer.so
19:04:13 T:1921326368    INFO: DVDVideoCodecAmlogic: Opened Amlogic Codec
19:04:13 T:1921326368   DEBUG: FactoryCodec - Video: am-h264 - Opened
19:04:13 T:1921326368  NOTICE: Creating video thread
19:04:13 T:1921211216  NOTICE: Thread DVDPlayerVideo start, auto delete: false
19:04:13 T:1921211216  NOTICE: running thread: video_thread
19:04:13 T:1921326368  NOTICE: Opening audio stream: 1 source: 256
19:04:13 T:1921326368  NOTICE: Finding audio codec for: 86018
19:04:13 T:1921211216   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
19:04:13 T:1921326368   DEBUG: FactoryCodec - Audio: passthrough - Opening
19:04:13 T:1921326368   DEBUG: FactoryCodec - Audio: passthrough - Failed
19:04:13 T:1921326368   DEBUG: FactoryCodec - Audio: FFmpeg - Opening
19:04:13 T:1921326368   DEBUG: FactoryCodec - Audio: FFmpeg - Opened
19:04:13 T:1921326368  NOTICE: Creating audio thread
19:04:13 T:1926015512  NOTICE: Thread DVDPlayerAudio start, auto delete: false
19:04:13 T:1921326368   DEBUG: virtual void CApplication::OnPlayBackStarted() : play state was 1, starting 1
19:04:13 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:04:13 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 3
19:04:13 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:04:13 T:1921211216   DEBUG: Previous line repeats 1 times.
19:04:13 T:1921211216   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(40000.000000, 1)
19:04:13 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:04:13 T:1926015512  NOTICE: running thread: CDVDPlayerAudio::Process()
19:04:13 T:1921211216   DEBUG: CAMLCodec::OpenDecoder, android version 19
19:04:13 T:1921211216   DEBUG: codecid_to_vformat, id(28) -> vformat(2)
19:04:13 T:1921211216   DEBUG: codec_tag_to_vdec_type, codec_tag(828601953) -> vdec_type(4)
19:04:13 T:1921211216   DEBUG: CAMLCodec::OpenDecoder hints.width(960), hints.height(544), hints.codec(28), hints.codec_tag(828601953), hints.pid(1)
19:04:13 T:1921211216   DEBUG: CAMLCodec::OpenDecoder hints.fpsrate(25), hints.fpsscale(1), hints.rfpsrate(25), hints.rfpsscale(1), video_rate(3840)
19:04:13 T:1921211216   DEBUG: CAMLCodec::OpenDecoder hints.aspect(1.777778), video_ratio.num(1), video_ratio.den(1)
19:04:13 T:1921211216   DEBUG: CAMLCodec::OpenDecoder hints.orientation(0), hints.forced_aspect(0), hints.extrasize(59)
19:04:13 T:1926015512  NOTICE: Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through)
19:04:13 T:1926015512   DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
19:04:13 T:1921211216  NOTICE: aml_get_sysfs_str k=/sys/class/display/mode v=720p
19:04:13 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:04:13 T:1929075544  NOTICE: Thread CAMLCodec start, auto delete: false
19:04:13 T:1929075544   DEBUG: CAMLCodec::Process Started
19:04:13 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:04:13 T:1738722768    INFO: CActiveAESink::OpenSink - initialize sink
19:04:13 T:1738722768   DEBUG: CActiveAESink::OpenSink - trying to open device AUDIOTRACK:AudioTrack
19:04:13 T:1921211216   DEBUG: DVDVideoCodecAmlogic: detected new framerate(25.000000), video_rate(3840)
19:04:13 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:04:13 T:1738722768   DEBUG: CActiveAESink::OpenSink - AUDIOTRACK Initialized:
19:04:13 T:1738722768   DEBUG:   Output Device : android
19:04:13 T:1738722768   DEBUG:   Sample Rate   : 48000
19:04:13 T:1738722768   DEBUG:   Sample Format : AE_FMT_S16NE
19:04:13 T:1738722768   DEBUG:   Channel Count : 2
19:04:13 T:1738722768   DEBUG:   Channel Layout: FL,FR
19:04:13 T:1738722768   DEBUG:   Frames        : 2048
19:04:13 T:1738722768   DEBUG:   Frame Samples : 4096
19:04:13 T:1738722768   DEBUG:   Frame Size    : 4
19:04:13 T:1738905240   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:04:13 T:1921211216   DEBUG: Previous line repeats 1 times.
19:04:13 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:04:13 T:1747504680   DEBUG: PlayBackRet CApplication::PlayFile(const CFileItem&, bool) : OpenFile succeed, play state 2
19:04:13 T:1747504680   DEBUG: virtual void CApplication::OnPlayBackStarted() : play state was 2, starting 0
19:04:13 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:04:13 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:04:13 T:1747504680   DEBUG: CGUIInfoManager::SetCurrentMovie(http://media.ndr.de/progressive/2013/1212/TV-20131212-1919-0742.hq.mp4)
19:04:13 T:1747504680   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
19:04:13 T:1747504680   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
19:04:13 T:1921211216  NOTICE:  fps: 25.000000, pwidth: 960, pheight: 544, dwidth: 960, dheight: 540
19:04:13 T:1921211216   DEBUG: int CDVDPlayerVideo::OutputPicture(const DVDVideoPicture*, double) - change configuration. 960x544. framerate: 25.00. format: BYPASS
19:04:13 T:1921211216  NOTICE: Display resolution DESKTOP : 1280x720 @ 60.00 - Full Screen (16)
19:04:13 T:1921211216   DEBUG: CXBMCRenderManager::Configure - 3
19:04:13 T:1747504680  NOTICE: Using GL_TEXTURE_2D
19:04:13 T:1747504680   DEBUG: GL: Requested render method: 0
19:04:13 T:1747504680  NOTICE: GL: Using BYPASS render method
19:04:13 T:1747504680  NOTICE: GL: NPOT texture support detected
19:04:13 T:1747504680   DEBUG: Activating window ID: 12005
19:04:13 T:1747504680   DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
19:04:13 T:1747504680   DEBUG: ------ Window Init (VideoFullScreen.xml) ------
19:04:13 T:1747504680   DEBUG: Window VideoFullScreen.xml was already loaded
19:04:13 T:1747504680   DEBUG: Alloc resources: 0.45m
19:04:13 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:04:13 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:04:13 T:1926015512   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(21333.333333, 0)
19:04:13 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:40441.000000, should be:-299103.337466, error:-339544.337466
19:04:13 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:04:13 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:04:13 T:1747504680   DEBUG: CAMLCodec::SetVideoRect:display(0,0,1280,720)
19:04:13 T:1747504680   DEBUG: CAMLCodec::SetVideoRect:gui(0,0,1920,1080)
19:04:13 T:1747504680   DEBUG: CAMLCodec::SetVideoRect:dst_rect(0,0,1280,720)
19:04:13 T:1747504680   DEBUG: CAMLCodec::SetVideoRect:m_stereo_mode(0)
19:04:13 T:1747504680   DEBUG: CAMLCodec::SetVideoRect:m_stereo_view(0)
19:04:13 T:1747504680  NOTICE: aml_set_sysfs_int k=/sys/class/video/axis v=0 0 1279 719
19:04:13 T:1747504680   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
19:04:15 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity2 - was:1701590.662534, should be:1679463.730980, error:-22126.931555
19:04:17 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity2 - was:3710602.730980, should be:3683166.248690, error:-27436.482290
19:04:21 T:1783418256   DEBUG: Thread JobWorker 1783418256 terminating (autodelete)
19:04:21 T:1747504680    INFO: void XCURL::DllLibCurlGlobal::CheckIdle() - Closing session to http://www.ardmediathek.de (easy=0x72d4b9e0, multi=0x725d9a90)
19:04:31 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:07:51 T:1921211216 WARNING: Previous line repeats 968 times.
19:07:51 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:07:52 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:07:52 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:07:56 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:07:57 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:07:57 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 4
19:07:57 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:07:57 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:07:57 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:07:57 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:223727389.247690, should be:223729679.618983, error:2290.371293
19:07:58 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:07:58 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:07:58 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:07:58 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:224320900.618983, should be:224599095.648070, error:278195.029087
19:07:58 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:07:58 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:224938645.648070, should be:224832363.285650, error:-106282.362420
19:08:00 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:01 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:01 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:08:01 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 4
19:08:01 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:08:01 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:08:01 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:01 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:08:01 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:08:01 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:227781868.285650, should be:227270687.663965, error:-511180.621685
19:08:01 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:01 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:08:01 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:01 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:02 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:08:02 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 4
19:08:02 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:08:02 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:08:02 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:02 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:02 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:08:02 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:08:02 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:03 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:229092057.663965, should be:227524067.988953, error:-1567989.675011
19:08:03 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:08:03 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:04 T:1921211216 WARNING: Previous line repeats 1 times.
19:08:04 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:04 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:08:04 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 4
19:08:04 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:08:04 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:228697784.988953, should be:227679354.327452, error:-1018430.661501
19:08:04 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:04 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:08:04 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:08:04 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:04 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:08:04 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:08:04 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:05 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:08:05 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 4
19:08:05 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:08:05 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:228397223.327452, should be:227867355.319346, error:-529868.008106
19:08:05 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:08:05 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:08:05 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:05 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:08:05 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:05 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:08:05 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:08:05 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:05 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:08:05 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 4
19:08:05 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:08:05 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:08:05 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:08:05 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:05 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:08:05 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:08:06 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:06 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:228859265.319346, should be:228096707.655302, error:-762557.664044
19:08:06 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:06 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:06 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:08:06 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:08:06 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:06 T:1921211216 WARNING: Previous line repeats 1 times.
19:08:06 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:06 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:08:06 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 4
19:08:06 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:08:06 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:08:06 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:07 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:08 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:08:08 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:08:08 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:08 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:09 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:09 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:231387108.655302, should be:228288349.316326, error:-3098759.338976
19:08:09 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:09 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:08:09 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:10 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:13 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:08:13 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 4
19:08:13 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:08:13 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:231946210.316326, should be:228503687.653713, error:-3442522.662614
19:08:13 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:08:13 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:08:13 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:13 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:08:13 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:08:13 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:13 T:1921211216 WARNING: Previous line repeats 1 times.
19:08:13 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:13 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:08:13 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 4
19:08:14 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:08:14 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:229446377.653713, should be:228683074.981244, error:-763302.672468
19:08:14 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:08:14 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:08:14 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:14 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:08:14 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:14 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:14 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:08:14 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:15 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:15 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:15 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:15 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:15 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:230380973.981244, should be:228851704.670164, error:-1529269.311081
19:08:15 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:08:15 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:16 T:1921211216 WARNING: Previous line repeats 1 times.
19:08:16 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:16 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:08:16 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 4
19:08:16 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:08:16 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:08:16 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:16 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:16 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:08:16 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:08:16 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:17 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:17 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:17 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:230709808.670164, should be:229096021.991814, error:-1613786.678349
19:08:17 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:08:17 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:17 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:08:18 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:18 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:08:18 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:08:18 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 4
19:08:18 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:08:18 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:08:18 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:18 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:08:18 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:230522353.991814, should be:229270018.014941, error:-1252335.976873
19:08:19 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:08:19 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:19 T:1921211216 WARNING: Previous line repeats 1 times.
19:08:19 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:20 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:08:20 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 4
19:08:20 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:08:20 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:08:20 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:08:20 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:20 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:08:20 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:20 T:1747504680   DEBUG: ------ Window Init (VideoOSD.xml) ------
19:08:20 T:1747504680    INFO: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY
19:08:20 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:08:20 T:1747504680   DEBUG: Load VideoOSD.xml: 85.64ms
19:08:20 T:1747504680   DEBUG: Alloc resources: 95.56ms  (86.05 ms skin load)
19:08:20 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:20 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:231216528.014941, should be:229478344.326737, error:-1738183.688204
19:08:20 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:20 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:08:20 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:21 T:1921211216 WARNING: Previous line repeats 2 times.
19:08:21 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:08:21 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:08:21 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:08:21 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:08:21 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:08:21 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 4
19:08:21 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:08:21 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:230058915.326737, should be:229746679.667223, error:-312235.659514
19:08:21 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:21 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:230038974.667223, should be:229806349.323638, error:-232625.343585
19:08:21 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:21 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:22 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:22 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:08:22 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:22 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:22 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:230971015.323638, should be:229873343.330631, error:-1097671.993006
19:08:23 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:08:23 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:23 T:1921211216 WARNING: Previous line repeats 2 times.
19:08:23 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:23 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:08:23 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 4
19:08:23 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:08:23 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:08:23 T:1921211216   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:08:23 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:23 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:08:23 T:1921326368   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:08:24 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:24 T:1921211216    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:08:24 T:1921211216 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:08:24 T:1926015512   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:231764828.330631, should be:230097009.993404, error:-1667818.337227
19:08:24 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:24 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:08:24 T:1926015512 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:08:25 T:1921211216 WARNING: Previous line repeats 1 times.
19:08:25 T:1921211216    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:08:26 T:1747504680   DEBUG: bool CApplication::ProcessMouse(): trying mouse action leftclick
19:08:26 T:1747504680  NOTICE: CDVDPlayer::CloseFile()
19:08:26 T:1747504680  NOTICE: DVDPlayer: waiting for threads to exit
19:08:26 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:08:26 T:1921326368  NOTICE: CDVDPlayer::OnExit()
19:08:26 T:1921326368   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:08:26 T:1921326368  NOTICE: DVDPlayer: closing audio stream
19:08:26 T:1921326368  NOTICE: Closing audio stream
19:08:26 T:1921326368  NOTICE: Waiting for audio thread to exit
19:08:26 T:1926015512   DEBUG: CDVDPlayerAudio::Process - Abort received, exiting thread
19:08:26 T:1926015512  NOTICE: thread end: CDVDPlayerAudio::OnExit()
19:08:26 T:1926015512   DEBUG: Thread DVDPlayerAudio 1926015512 terminating
19:08:26 T:1921326368  NOTICE: Closing audio device
19:08:26 T:1921326368  NOTICE: Deleting audio codec
19:08:26 T:1921326368  NOTICE: DVDPlayer: closing video stream
19:08:26 T:1738905240   DEBUG: CActiveAE::DiscardStream - audio stream deleted
19:08:26 T:1738905240   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:08:26 T:1921326368  NOTICE: Closing video stream
19:08:26 T:1921326368  NOTICE: waiting for video thread to exit
19:08:26 T:1738905240   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:08:27 T:1921211216  NOTICE: thread end: video_thread
19:08:27 T:1921211216   DEBUG: Thread DVDPlayerVideo 1921211216 terminating
19:08:27 T:1921326368  NOTICE: deleting video codec
19:08:27 T:1921326368   DEBUG: CAMLCodec::CloseDecoder
19:08:27 T:1929075544   DEBUG: CAMLCodec::Process Stopped
19:08:27 T:1929075544   DEBUG: Thread CAMLCodec 1929075544 terminating
19:08:27 T:1738722768    INFO: CActiveAESink::OpenSink - initialize sink
19:08:27 T:1738722768   DEBUG: CActiveAESink::OpenSink - trying to open device AUDIOTRACK:AudioTrack
19:08:27 T:1738722768   DEBUG: CActiveAESink::OpenSink - AUDIOTRACK Initialized:
19:08:27 T:1738722768   DEBUG:   Output Device : android
19:08:27 T:1738722768   DEBUG:   Sample Rate   : 44100
19:08:27 T:1738722768   DEBUG:   Sample Format : AE_FMT_S16NE
19:08:27 T:1738722768   DEBUG:   Channel Count : 2
19:08:27 T:1738722768   DEBUG:   Channel Layout: FL,FR
19:08:27 T:1738722768   DEBUG:   Frames        : 1881
19:08:27 T:1738722768   DEBUG:   Frame Samples : 3762
19:08:27 T:1738722768   DEBUG:   Frame Size    : 4
19:08:27 T:1738905240   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:08:27 T:1921326368   DEBUG: Previous line repeats 1 times.
19:08:27 T:1921326368  NOTICE: CDVDPlayer::OnExit() deleting demuxer
19:08:27 T:1921326368  NOTICE: CDVDPlayer::OnExit() deleting input stream
19:08:27 T:1784921144   DEBUG: Thread FileCache 1784921144 terminating
19:08:27 T:1921326368   DEBUG: virtual void CApplication::OnPlayBackStopped() : play state was 2, starting 0
19:08:27 T:1921326368   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
19:08:27 T:1921326368   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
19:08:27 T:1747504680  NOTICE: DVDPlayer: finished waiting
19:08:27 T:1747504680   DEBUG: LinuxRendererGL: Cleaning up GL resources
19:08:27 T:1747504680   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
19:08:27 T:1921326368   DEBUG: Thread DVDPlayer 1921326368 terminating
19:08:27 T:1747504680   DEBUG: ------ Window Deinit (VideoOSD.xml) ------
19:08:27 T:1747504680   DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
19:08:27 T:1747504680   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
19:08:27 T:1747504680   DEBUG: ------ Window Init (MyVideoNav.xml) ------
19:08:27 T:1747504680   DEBUG: Window MyVideoNav.xml was already loaded
19:08:27 T:1747504680   DEBUG: Alloc resources: 9.22m
19:08:27 T:1747504680   DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.ardmediathek_de/?hideshowname=True&mode=listVideosRss&name=Typisch!&nextpage=True&showName=Typisch!&url=http%3a%2f%2fwww.ardmediathek.de%2ftv%2fTypisch%2fSendung%3fdocumentId%3d14049256%26bcastId%3d14049256%26m23644322%3dquelle.tv%26rss%3dtrue)
19:08:27 T:1747504680   DEBUG:   ParentPath = [plugin://plugin.video.ardmediathek_de/?hideshowname=True&mode=listVideosRss&name=Typisch!&nextpage=True&showName=Typisch!&url=http%3a%2f%2fwww.ardmediathek.de%2ftv%2fTypisch%2fSendung%3fdocumentId%3d14049256%26bcastId%3d14049256%26m23644322%3dquelle.tv%26rss%3dtrue]
19:08:27 T:1747504680   DEBUG: Loading items: 45, directory: plugin://plugin.video.ardmediathek_de/?hideshowname=True&mode=listVideosRss&name=Typisch!&nextpage=True&showName=Typisch!&url=http%3a%2f%2fwww.ardmediathek.de%2ftv%2fTypisch%2fSendung%3fdocumentId%3d14049256%26bcastId%3d14049256%26m23644322%3dquelle.tv%26rss%3dtrue sort method: 0, ascending: false
19:08:27 T:1747504680   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 1 ms for 3 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=1
19:08:27 T:1927830016  NOTICE: Thread BackgroundLoader start, auto delete: false
19:08:27 T:1747504680   DEBUG: Unfocus WindowID: 10025, ControlID: 51
19:08:27 T:1927830016   DEBUG: Thread BackgroundLoader 1927830016 terminating
19:08:27 T:1747504680  NOTICE: CDVDPlayer::CloseFile()
19:08:27 T:1747504680  NOTICE: DVDPlayer: waiting for threads to exit
19:08:27 T:1747504680  NOTICE: DVDPlayer: finished waiting
19:08:27 T:1747504680   DEBUG: LinuxRendererGL: Cleaning up GL resources
19:08:27 T:1747504680  NOTICE: CDVDPlayer::CloseFile()
19:08:27 T:1747504680  NOTICE: DVDPlayer: waiting for threads to exit
19:08:27 T:1747504680  NOTICE: DVDPlayer: finished waiting
19:08:27 T:1747504680   DEBUG: LinuxRendererGL: Cleaning up GL resources
19:08:28 T:1924530200  NOTICE: Thread JobWorker start, auto delete: true
19:08:28 T:1924530200   DEBUG: virtual bool CSaveFileStateJob::DoWork() - Saving file state for video item plugin://plugin.video.ardmediathek_de/?url=http%3A%2F%2Fwww.ardmediathek.de%2Ftv%2FTypisch%2FMach-weiter-Elfriede%2FNDR-Fernsehen%2FVideo%3FdocumentId%3D18607920%26amp%3BbcastId%3D14049256&mode=playVideoUrl
19:08:32 T:1738905240   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:08:57 T:1747504680   DEBUG: SECTION:UnloadDelayed(DLL: libamplayer.so)
19:08:57 T:1747504680   DEBUG: Unloading: libamplayer.so
19:08:58 T:1747504680    INFO: void XCURL::DllLibCurlGlobal::CheckIdle() - Closing session to http://media.ndr.de (easy=0x735befd0, multi=0x71af72d0)
19:08:58 T:1924530200   DEBUG: Thread JobWorker 1924530200 terminating (autodelete)
19:09:08 T:1747504680  NOTICE: aml_set_sysfs_int k=/sys/class/display/mode v=720p
19:09:08 T:1747504680   DEBUG: bool CWinSystemEGL::CreateWindow(RESOLUTION_INFO&): Created surface of size 1920x1080
19:09:08 T:1747504680  NOTICE: GL_VENDOR = ARM
19:09:08 T:1747504680  NOTICE: GL_RENDERER = Mali-450 MP
19:09:08 T:1747504680  NOTICE: GL_VERSION = OpenGL ES 2.0
19:09:08 T:1747504680  NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 1.00
19:09:08 T:1747504680   DEBUG: GLES: Extension Support Test - GL_NVX_gpu_memory_info NO
19:09:08 T:1747504680  NOTICE: GL_EXTENSIONS = GL_EXT_debug_marker GL_OES_texture_npot GL_OES_compressed_ETC1_RGB8_texture GL_OES_standard_derivatives GL_OES_EGL_image GL_OES_depth24 GL_ARM_rgba8 GL_ARM_mali_shader_binary GL_OES_depth_texture GL_OES_packed_depth_stencil GL_EXT_texture_format_BGRA8888 GL_OES_vertex_half_float GL_EXT_blend_minmax GL_OES_EGL_image_external GL_OES_EGL_sync GL_OES_rgb8_rgba8 GL_EXT_multisampled_render_to_texture GL_EXT_discard_framebuffer GL_OES_get_program_binary GL_ARM_mali_program_binary GL_EXT_shader_texture_lod GL_EXT_robustness GL_OES_depth_texture_cube_map GL_KHR_debug
19:09:08 T:1747504680   DEBUG: GLES: Extension Support Test - GL_EXT_texture_format_BGRA8888 YES
19:09:08 T:1747504680   DEBUG: GLES: Extension Support Test - GL_IMG_texture_format_BGRA8888 NO
19:09:08 T:1747504680   DEBUG: GLES: Extension Support Test - GL_APPLE_texture_format_BGRA8888 NO
19:09:08 T:1747504680   DEBUG: GUI Shader - Tried to Initialise again. Was this intentional?
19:09:08 T:1747504680    INFO: GLES: Enabling VSYNC
19:09:08 T:1747504680    INFO: GLES: Selected vsync mode 10
19:09:13 T:1747504680   DEBUG: bool CApplication::ProcessMouse(): trying mouse action leftclick
19:09:13 T:1747504680   DEBUG: ------ Window Init (DialogContextMenu.xml) ------
19:09:13 T:1747504680   DEBUG: Window DialogContextMenu.xml was already loaded
19:09:13 T:1747504680   DEBUG: Alloc resources: 1.68m
19:09:15 T:1747504680   DEBUG: bool CApplication::ProcessMouse(): trying mouse action leftclick
19:09:15 T:1747504680   DEBUG: Unfocus WindowID: 10106, ControlID: 1001
19:09:15 T:1747504680   DEBUG: Unfocus WindowID: 10025, ControlID: 51
19:09:15 T:1747504680   DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------
19:09:15 T:1747504680   DEBUG: virtual bool CGUIWindowVideoBase::OnPlayMedia(int) plugin://plugin.video.ardmediathek_de/?url=http%3A%2F%2Fwww.ardmediathek.de%2Ftv%2FTypisch%2FMach-weiter-Elfriede%2FNDR-Fernsehen%2FVideo%3FdocumentId%3D18607920%26amp%3BbcastId%3D14049256&mode=playVideoUrl
19:09:15 T:1747504680   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
19:09:15 T:1747504680   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
19:09:15 T:1747504680   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
19:09:15 T:1747504680   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
19:09:15 T:1747504680   DEBUG: bool XFILE::CPluginDirectory::StartScript(const CStdString&, bool) - calling plugin ARD Mediathek('plugin://plugin.video.ardmediathek_de/','8','?url=http%3A%2F%2Fwww.ardmediathek.de%2Ftv%2FTypisch%2FMach-weiter-Elfriede%2FNDR-Fernsehen%2FVideo%3FdocumentId%3D18607920%26amp%3BbcastId%3D14049256&mode=playVideoUrl')
19:09:15 T:1747504680   DEBUG: bool XFILE::CPluginDirectory::WaitOnScriptResult(const CStdString&, int, const CStdString&, bool) - waiting on the ARD Mediathek (id=10) plugin...
19:09:15 T:1921087064  NOTICE: Thread LanguageInvoker start, auto delete: false
19:09:15 T:1921087064    INFO: initializing python engine.
19:09:15 T:1921087064   DEBUG: CPythonInvoker(10, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): start processing
19:09:15 T:1921087064  NOTICE: -->Python Interpreter Initialized<--
19:09:15 T:1921087064   DEBUG: CPythonInvoker(10, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): the source file to load is "/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py"
19:09:15 T:1921087064   DEBUG: CPythonInvoker(10, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): setting the Python path to /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.pyamf/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.simplejson/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.beautifulsoup4/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/script.module.pil/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.common.plugin.cache/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.requests/lib:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.mechanize/lib:/data/data/hk.minix.xbmc/cache/apk/assets/addons/xbmc.debug/:/storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/script.module.xbmcswift2/lib:/:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python26.zip:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/plat-linux3:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-tk:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-old:/data/app/hk.minix.xbmc-1.apk/assets/python2.6/lib/python2.6/lib-dynload
19:09:15 T:1921087064   DEBUG: CPythonInvoker(10, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): entering source directory /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de
19:09:15 T:1921087064   DEBUG: CPythonInvoker(10, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): instantiating addon using automatically obtained id of "plugin.video.ardmediathek_de" dependent on version 2.1.0 of the xbmc.python api
19:09:15 T:1921087064  NOTICE: get: http://www.ardmediathek.de/tv/Typisch/Mach-weiter-Elfriede/NDR-Fernsehen/Video?documentId=18607920&amp;bcastId=14049256
19:09:16 T:1921087064  NOTICE: get: http://www.ardmediathek.de/play/media/18607920?devicetype=pc&features=flash
19:09:16 T:1747504680   DEBUG: bool XFILE::CPluginDirectory::WaitOnScriptResult(const CStdString&, int, const CStdString&, bool)- plugin returned successfully
19:09:16 T:1921087064    INFO: CPythonInvoker(10, /storage/emulated/0/Android/data/hk.minix.xbmc/files/.xbmc/addons/plugin.video.ardmediathek_de/default.py): script successfully run
19:09:16 T:1747504680    INFO: void XCURL::DllLibCurlGlobal::easy_aquire(const char*, const char*, XCURL::CURL_HANDLE**, XCURL::CURLM**) - Created session to http://media.ndr.de
19:09:16 T:1921087064    INFO: Python script stopped
19:09:16 T:1921087064   DEBUG: Thread LanguageInvoker 1921087064 terminating
19:09:16 T:1747504680   DEBUG: CCurlFile::GetMimeType - http://media.ndr.de/progressive/2013/1212/TV-20131212-1919-0742.hq.mp4 -> video/mp4
19:09:16 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers(http://media.ndr.de/progressive/2013/1212/TV-20131212-1919-0742.hq.mp4)
19:09:16 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
19:09:16 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
19:09:16 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
19:09:16 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
19:09:16 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
19:09:16 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
19:09:16 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
19:09:16 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
19:09:16 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams
19:09:16 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp
19:09:16 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2
19:09:16 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
19:09:16 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
19:09:16 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
19:09:16 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
19:09:16 T:1747504680   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
19:09:16 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
19:09:16 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
19:09:16 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
19:09:16 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
19:09:16 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
19:09:16 T:1747504680   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
19:09:16 T:1747504680  NOTICE: DVDPlayer: Opening: http://media.ndr.de/progressive/2013/1212/TV-20131212-1919-0742.hq.mp4
19:09:16 T:1747504680 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:09:16 T:1747504680   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
19:09:16 T:1747504680   DEBUG: LinuxRendererGL: Cleaning up GL resources
19:09:16 T:1920657104  NOTICE: Thread DVDPlayer start, auto delete: false
19:09:16 T:1920657104  NOTICE: Creating InputStream
19:09:16 T:1920657104   DEBUG: CFileCache::Open - opening <progressive/2013/1212/TV-20131212-1919-0742.hq.mp4> using cache
19:09:16 T:1920657104   DEBUG: CurlFile::Open(0x728457b0) http://media.ndr.de/progressive/2013/1212/TV-20131212-1919-0742.hq.mp4
19:09:17 T:1747504680   DEBUG: ------ Window Init (DialogBusy.xml) ------
19:09:17 T:1747504680   DEBUG: Window DialogBusy.xml was already loaded
19:09:17 T:1747504680   DEBUG: Alloc resources: 0.14m
19:09:17 T:1920657104  NOTICE: Creating Demuxer
19:09:17 T:1921087064  NOTICE: Thread FileCache start, auto delete: false
19:09:17 T:1920657104   DEBUG: bool CDVDDemuxFFmpeg::Open(CDVDInputStream*) - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
19:09:19 T:1920657104   DEBUG: bool CDVDDemuxFFmpeg::Open(CDVDInputStream*) - avformat_find_stream_info starting
19:09:19 T:1920657104   DEBUG: bool CDVDDemuxFFmpeg::Open(CDVDInputStream*) - av_find_stream_info finished
19:09:19 T:1920657104   DEBUG: CDVDInputStreamFile::SetReadRate - set cache throttle rate to 354658 bytes per second
19:09:19 T:1920657104  NOTICE: Opening video stream: 0 source: 256
19:09:19 T:1920657104  NOTICE: Creating video codec with codec id: 28
19:09:19 T:1920657104   DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no AMCodec:yes MediaCodec:yes OpenMax:no libstagefright:yes VDPAU:no VAAPI:no iMXVPU:no
19:09:19 T:1920657104   DEBUG: FactoryCodec - Video: amcodec - Opening
19:09:19 T:1920657104    INFO: CBitstreamConverter::Open bitstream to annexb init
19:09:19 T:1920657104   DEBUG: SECTION:LoadDLL(libamplayer.so)
19:09:19 T:1920657104   DEBUG: Loading: /system/lib/libamplayer.so
19:09:19 T:1920657104    INFO: DVDVideoCodecAmlogic: Opened Amlogic Codec
19:09:19 T:1920657104   DEBUG: FactoryCodec - Video: am-h264 - Opened
19:09:19 T:1920657104  NOTICE: Creating video thread
19:09:19 T:1920713320  NOTICE: Thread DVDPlayerVideo start, auto delete: false
19:09:19 T:1920657104  NOTICE: Opening audio stream: 1 source: 256
19:09:19 T:1920657104  NOTICE: Finding audio codec for: 86018
19:09:19 T:1920657104   DEBUG: FactoryCodec - Audio: passthrough - Opening
19:09:19 T:1920657104   DEBUG: FactoryCodec - Audio: passthrough - Failed
19:09:19 T:1920713320  NOTICE: running thread: video_thread
19:09:19 T:1920657104   DEBUG: FactoryCodec - Audio: FFmpeg - Opening
19:09:19 T:1920713320   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
19:09:19 T:1920657104   DEBUG: FactoryCodec - Audio: FFmpeg - Opened
19:09:19 T:1920657104  NOTICE: Creating audio thread
19:09:19 T:1921210368  NOTICE: Thread DVDPlayerAudio start, auto delete: false
19:09:19 T:1920657104   DEBUG: virtual void CDVDPlayer::Process() - Start position set to last stopped position: 230000
19:09:19 T:1920657104   DEBUG: virtual bool CDVDDemuxFFmpeg::SeekTime(int, bool, double*) - unknown position after seek
19:09:19 T:1920657104   DEBUG: virtual void CDVDPlayer::Process() - starting demuxer from: 230000
19:09:19 T:1920657104   DEBUG: virtual void CApplication::OnPlayBackStarted() : play state was 1, starting 1
19:09:19 T:1920657104   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:09:19 T:1920657104   DEBUG: CDVDPlayer::SetCaching - caching state 3
19:09:19 T:1920713320   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:09:19 T:1921210368  NOTICE: running thread: CDVDPlayerAudio::Process()
19:09:19 T:1920713320   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:09:19 T:1747504680   DEBUG: PlayBackRet CApplication::PlayFile(const CFileItem&, bool) : OpenFile succeed, play state 2
19:09:19 T:1747504680   DEBUG: virtual void CApplication::OnPlayBackStarted() : play state was 2, starting 0
19:09:19 T:1747504680   DEBUG: CGUIInfoManager::SetCurrentMovie(http://media.ndr.de/progressive/2013/1212/TV-20131212-1919-0742.hq.mp4)
19:09:19 T:1921087064    INFO: void XCURL::DllLibCurlGlobal::easy_aquire(const char*, const char*, XCURL::CURL_HANDLE**, XCURL::CURLM**) - Created session to http://media.ndr.de
19:09:19 T:1921087064   DEBUG: CurlFile::CReadState::Connect - Resume from position 52583206
19:09:19 T:1747504680   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
19:09:19 T:1747504680   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
19:09:19 T:1747504680   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
19:09:21 T:1920713320   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(230680000.000000, 1)
19:09:21 T:1920713320    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:09:21 T:1920713320   DEBUG: CAMLCodec::OpenDecoder, android version 19
19:09:21 T:1920713320   DEBUG: codecid_to_vformat, id(28) -> vformat(2)
19:09:21 T:1920713320   DEBUG: codec_tag_to_vdec_type, codec_tag(828601953) -> vdec_type(4)
19:09:21 T:1920713320   DEBUG: CAMLCodec::OpenDecoder hints.width(960), hints.height(544), hints.codec(28), hints.codec_tag(828601953), hints.pid(1)
19:09:21 T:1920713320   DEBUG: CAMLCodec::OpenDecoder hints.fpsrate(25), hints.fpsscale(1), hints.rfpsrate(25), hints.rfpsscale(1), video_rate(3840)
19:09:21 T:1920713320   DEBUG: CAMLCodec::OpenDecoder hints.aspect(1.777778), video_ratio.num(1), video_ratio.den(1)
19:09:21 T:1920713320   DEBUG: CAMLCodec::OpenDecoder hints.orientation(0), hints.forced_aspect(0), hints.extrasize(59)
19:09:21 T:1920713320  NOTICE: aml_get_sysfs_str k=/sys/class/display/mode v=720p
19:09:21 T:1920713320   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:09:21 T:1920341200  NOTICE: Thread CAMLCodec start, auto delete: false
19:09:21 T:1920341200   DEBUG: CAMLCodec::Process Started
19:09:21 T:1920713320 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:09:21 T:1921210368   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(230698666.666667, 0)
19:09:21 T:1921210368  NOTICE: Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through)
19:09:21 T:1921210368   DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
19:09:21 T:1738722768    INFO: CActiveAESink::OpenSink - initialize sink
19:09:21 T:1738722768   DEBUG: CActiveAESink::OpenSink - trying to open device AUDIOTRACK:AudioTrack
19:09:21 T:1738722768   DEBUG: CActiveAESink::OpenSink - AUDIOTRACK Initialized:
19:09:21 T:1738722768   DEBUG:   Output Device : android
19:09:21 T:1738722768   DEBUG:   Sample Rate   : 48000
19:09:21 T:1738722768   DEBUG:   Sample Format : AE_FMT_S16NE
19:09:21 T:1738722768   DEBUG:   Channel Count : 2
19:09:21 T:1738722768   DEBUG:   Channel Layout: FL,FR
19:09:21 T:1738722768   DEBUG:   Frames        : 2048
19:09:21 T:1738722768   DEBUG:   Frame Samples : 4096
19:09:21 T:1738722768   DEBUG:   Frame Size    : 4
19:09:21 T:1738905240   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:09:21 T:1920657104   DEBUG: Previous line repeats 1 times.
19:09:21 T:1920657104   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:09:21 T:1920713320 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:09:21 T:1920713320 WARNING: Previous line repeats 1 times.
19:09:21 T:1920713320   DEBUG: DVDVideoCodecAmlogic: detected new framerate(25.000000), video_rate(3840)
19:09:22 T:1920713320 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:09:22 T:1920713320    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:09:22 T:1920713320  NOTICE:  fps: 25.000000, pwidth: 960, pheight: 544, dwidth: 960, dheight: 540
19:09:22 T:1920713320   DEBUG: int CDVDPlayerVideo::OutputPicture(const DVDVideoPicture*, double) - change configuration. 960x544. framerate: 25.00. format: BYPASS
19:09:22 T:1920713320  NOTICE: Display resolution DESKTOP : 1280x720 @ 60.00 - Full Screen (16)
19:09:22 T:1920713320   DEBUG: CXBMCRenderManager::Configure - 3
19:09:22 T:1747504680   DEBUG: Activating window ID: 12005
19:09:22 T:1747504680   DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
19:09:22 T:1747504680   DEBUG: ------ Window Init (VideoFullScreen.xml) ------
19:09:22 T:1747504680   DEBUG: Window VideoFullScreen.xml was already loaded
19:09:22 T:1747504680   DEBUG: Alloc resources: 2.12m
19:09:22 T:1747504680  NOTICE: Using GL_TEXTURE_2D
19:09:22 T:1747504680   DEBUG: GL: Requested render method: 0
19:09:22 T:1747504680  NOTICE: GL: Using BYPASS render method
19:09:22 T:1747504680  NOTICE: GL: NPOT texture support detected
19:09:23 T:1920657104   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:09:23 T:1920657104   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:09:23 T:1920713320   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:09:23 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:230681932.000000, should be:230720000.000000, error:38068.000000
19:09:23 T:1747504680   DEBUG: CAMLCodec::SetVideoRect:display(0,0,1280,720)
19:09:23 T:1920713320 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:09:23 T:1747504680   DEBUG: CAMLCodec::SetVideoRect:gui(0,0,1920,1080)
19:09:23 T:1747504680   DEBUG: CAMLCodec::SetVideoRect:dst_rect(0,0,1280,720)
19:09:23 T:1747504680   DEBUG: CAMLCodec::SetVideoRect:m_stereo_mode(0)
19:09:23 T:1747504680   DEBUG: CAMLCodec::SetVideoRect:m_stereo_view(0)
19:09:23 T:1747504680  NOTICE: aml_set_sysfs_int k=/sys/class/video/axis v=0 0 1279 719
19:09:23 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:230843956.000000, should be:230720000.000000, error:-123956.000000
19:09:23 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:230847169.000000, should be:230720000.000000, error:-127169.000000
19:09:23 T:1920713320    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:09:23 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:231317069.000000, should be:231216563.952316, error:-100505.047684
19:09:23 T:1921210368 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
19:09:24 T:1920657104   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:09:24 T:1920657104   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:09:24 T:1747504680   DEBUG: ------ Window Init (DialogSeekBar.xml) ------
19:09:24 T:1747504680   DEBUG: Window DialogSeekBar.xml was already loaded
19:09:24 T:1747504680   DEBUG: Alloc resources: 0.04m
19:09:24 T:1920713320   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:09:24 T:1920657104   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:09:37 T:1747504680   DEBUG: ------ Window Init (VideoOSD.xml) ------
19:09:37 T:1747504680   DEBUG: Window VideoOSD.xml was already loaded
19:09:37 T:1747504680   DEBUG: Alloc resources: 18.05m
19:09:46 T:1747504680   DEBUG: bool CApplication::ProcessMouse(): trying mouse action leftclick
19:09:46 T:1920657104   DEBUG: demuxer seek to: 180828
19:09:46 T:1920657104   DEBUG: virtual bool CDVDDemuxFFmpeg::SeekTime(int, bool, double*) - unknown position after seek
19:09:46 T:1920657104   DEBUG: demuxer seek to: 180828, success
19:09:46 T:1920713320   DEBUG: CAMLCodec::Reset
19:09:46 T:1920713320   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:09:46 T:1920713320   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
19:09:46 T:1921210368   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
19:09:46 T:1747504680   DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc
19:09:46 T:1747504680   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek
19:09:46 T:1747504680   DEBUG: Unfocus WindowID: 12901, ControlID: 87
19:09:47 T:1921087064   DEBUG: CurlFile::CReadState::Connect - Resume from position 41173618
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:2 dts:180320000.000000 to get to start point at 180828000.000000
19:09:48 T:1920713320    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:2 dts:180360000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180309333.333333 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:09:48 T:1920657104   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180330666.666667 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180352000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180373333.333333 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:2 dts:180400000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:2 dts:180440000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:2 dts:180480000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:2 dts:180520000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:2 dts:180560000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180394666.666667 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180416000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180437333.333333 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180458666.666667 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180480000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180501333.333333 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180522666.666667 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180544000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180565333.333333 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:2 dts:180600000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:2 dts:180640000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:2 dts:180680000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:2 dts:180720000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:2 dts:180760000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180586666.666667 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180608000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180629333.333333 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180650666.666667 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180672000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180693333.333333 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180714666.666667 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180736000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180757333.333333 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180778666.666667 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:2 dts:180800000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180800000.000000 to get to start point at 180828000.000000
19:09:48 T:1920657104   DEBUG: bool CDVDPlayer::CheckPlayerInit(CCurrentStream&, unsigned int) - dropping packet type:1 dts:180821333.333333 to get to start point at 180828000.000000
19:09:50 T:1747504680   DEBUG: bool CApplication::ProcessMouse(): trying mouse action leftclick
19:09:50 T:1747504680   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:09:50 T:1920713320   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:09:50 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:180828456.000000, should be:180052675.666031, error:-775780.333969
19:09:50 T:1747504680   DEBUG: Unfocus WindowID: 12901, ControlID: 202
19:09:50 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:180054213.666031, should be:180160336.319823, error:106122.653792
19:09:50 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:180162764.319823, should be:180270007.007470, error:107242.687648
19:09:50 T:1920713320   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(180840000.000000, 1)
19:09:50 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:180840660.000000, should be:180292337.327929, error:-548322.672071
19:09:50 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:180294885.327929, should be:180401669.669607, error:106784.341678
19:09:50 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:180402585.669607, should be:180509336.323399, error:106750.653792
19:09:50 T:1921210368   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(180842666.666667, 0)
19:09:50 T:1747504680   DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
19:09:52 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity2 - was:182538916.323399, should be:182520777.704302, error:-18138.619097
19:09:54 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity2 - was:184548451.704302, should be:184529972.985640, error:-18478.718662
19:10:01 T:1747504680   DEBUG: ------ Window Deinit (VideoOSD.xml) ------
19:10:02 T:1747504680   DEBUG: ------ Window Init (VideoOSD.xml) ------
19:10:02 T:1747504680   DEBUG: Window VideoOSD.xml was already loaded
19:10:02 T:1747504680   DEBUG: Alloc resources: 17.22m
19:10:09 T:1920713320 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:10:11 T:1747504680 WARNING: Previous line repeats 9 times.
19:10:11 T:1747504680   DEBUG: ------ Window Deinit (VideoOSD.xml) ------
19:10:11 T:1920713320 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:10:29 T:1920713320 WARNING: Previous line repeats 84 times.
19:10:29 T:1920713320    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:10:29 T:1920713320    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:10:30 T:1920713320    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:10:30 T:1920713320    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:10:30 T:1920713320 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:10:31 T:1920713320    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:10:31 T:1920713320    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:10:32 T:1920713320    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:10:32 T:1920713320    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:10:32 T:1920713320 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:10:33 T:1920713320    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:10:34 T:1920657104   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:10:34 T:1747504680   DEBUG: ------ Window Init (DialogSeekBar.xml) ------
19:10:34 T:1747504680   DEBUG: Window DialogSeekBar.xml was already loaded
19:10:34 T:1747504680   DEBUG: Alloc resources: 0.70m
19:10:34 T:1920713320   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:10:34 T:1920657104   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:10:34 T:1920657104   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:10:36 T:1747504680   DEBUG: ------ Window Init (VideoOSD.xml) ------
19:10:36 T:1747504680   DEBUG: Window VideoOSD.xml was already loaded
19:10:36 T:1747504680   DEBUG: Alloc resources: 9.63m
19:10:49 T:1747504680   DEBUG: bool CApplication::ProcessMouse(): trying mouse action mousedrag
19:10:50 T:1747504680   DEBUG: Previous line repeats 31 times.
19:10:50 T:1747504680   DEBUG: Keyboard: scancode: 0x71, sym: 0x0132, unicode: 0x0000, modifier: 0x40
19:10:50 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): ctrl-leftctrl (0x1f0d0) pressed, action is
19:10:50 T:1747504680   DEBUG: Keyboard: scancode: 0x43, sym: 0x0008, unicode: 0x0000, modifier: 0x40
19:10:50 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): ctrl-backspace (0x1f008) pressed, action is
19:11:13 T:1747504680   DEBUG: bool CApplication::ProcessMouse(): trying mouse action leftclick
19:11:13 T:1747504680   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:11:13 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:224418702.985640, should be:224671338.285491, error:252635.299851
19:11:13 T:1920713320   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:11:13 T:1920713320    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:11:13 T:1747504680   DEBUG: Unfocus WindowID: 12901, ControlID: 202
19:11:13 T:1747504680   DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
19:11:15 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity2 - was:226694053.285491, should be:226675587.517063, error:-18465.768428
19:11:17 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity2 - was:228748807.517063, should be:228734115.799900, error:-14691.717163
19:11:17 T:1920713320 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:11:19 T:1920713320    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:11:19 T:1920657104   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:11:19 T:1920713320   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:11:19 T:1747504680   DEBUG: ------ Window Init (DialogSeekBar.xml) ------
19:11:19 T:1747504680   DEBUG: Window DialogSeekBar.xml was already loaded
19:11:19 T:1747504680   DEBUG: Alloc resources: 0.55m
19:11:19 T:1920657104   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:11:19 T:1920657104   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:11:25 T:1747504680   DEBUG: bool CApplication::ProcessMouse(): trying mouse action leftclick
19:11:33 T:1747504680   DEBUG: Previous line repeats 1 times.
19:11:33 T:1747504680   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:11:33 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:230720853.799900, should be:231002005.984582, error:281152.184682
19:11:33 T:1920713320   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:11:33 T:1920713320    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:11:33 T:1747504680   DEBUG: Unfocus WindowID: 12901, ControlID: 202
19:11:33 T:1747504680   DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
19:11:35 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity2 - was:233010288.984582, should be:232991027.558468, error:-19261.426114
19:11:35 T:1920713320 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:11:37 T:1921210368 WARNING: Previous line repeats 1 times.
19:11:37 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity2 - was:235020760.558468, should be:234998828.645550, error:-21931.912918
19:11:38 T:1920713320    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:11:38 T:1920657104   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:11:38 T:1920713320   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:11:38 T:1920657104   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:11:38 T:1747504680   DEBUG: ------ Window Init (DialogSeekBar.xml) ------
19:11:38 T:1747504680   DEBUG: Window DialogSeekBar.xml was already loaded
19:11:38 T:1747504680   DEBUG: Alloc resources: 0.42m
19:11:38 T:1920657104   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:11:41 T:1747504680   DEBUG: bool CApplication::ProcessMouse(): trying mouse action leftclick
19:11:41 T:1747504680   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:11:41 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:235484826.645550, should be:235807006.983311, error:322180.337761
19:11:41 T:1920713320   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:11:41 T:1920713320    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:11:41 T:1747504680   DEBUG: Unfocus WindowID: 12901, ControlID: 202
19:11:41 T:1747504680   DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
19:11:41 T:1920713320 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
19:11:42 T:1920713320    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
19:11:43 T:1920657104   DEBUG: CDVDPlayer::SetCaching - caching state 1
19:11:43 T:1920713320   DEBUG: CAMLCodec::SetSpeed, speed(0)
19:11:43 T:1920657104   DEBUG: CDVDPlayer::HandleMessages - player started 2
19:11:43 T:1747504680   DEBUG: ------ Window Init (DialogSeekBar.xml) ------
19:11:43 T:1747504680   DEBUG: Window DialogSeekBar.xml was already loaded
19:11:43 T:1747504680   DEBUG: Alloc resources: 1.35m
19:11:43 T:1920657104   DEBUG: CDVDPlayer::HandleMessages - player started 1
19:11:55 T:1747504680   DEBUG: bool CApplication::ProcessMouse(): trying mouse action leftclick
19:11:55 T:1747504680  NOTICE: CDVDPlayer::CloseFile()
19:11:55 T:1747504680  NOTICE: DVDPlayer: waiting for threads to exit
19:11:55 T:1920657104  NOTICE: CDVDPlayer::OnExit()
19:11:55 T:1920657104   DEBUG: CDVDPlayer::SetCaching - caching state 0
19:11:55 T:1920657104  NOTICE: DVDPlayer: closing audio stream
19:11:55 T:1920657104  NOTICE: Closing audio stream
19:11:55 T:1920657104  NOTICE: Waiting for audio thread to exit
19:11:55 T:1921210368   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:237570746.983311, should be:237848005.954224, error:277258.970913
19:11:55 T:1921210368  NOTICE: thread end: CDVDPlayerAudio::OnExit()
19:11:55 T:1921210368   DEBUG: Thread DVDPlayerAudio 1921210368 terminating
19:11:55 T:1920713320   DEBUG: CAMLCodec::SetSpeed, speed(1000)
19:11:55 T:1920713320    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:11:55 T:1920657104  NOTICE: Closing audio device
19:11:55 T:1738905240   DEBUG: CActiveAE::DiscardStream - audio stream deleted
19:11:55 T:1920657104  NOTICE: Deleting audio codec
19:11:55 T:1738905240   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:11:55 T:1920657104  NOTICE: DVDPlayer: closing video stream
19:11:55 T:1920657104  NOTICE: Closing video stream
19:11:55 T:1920657104  NOTICE: waiting for video thread to exit
19:11:55 T:1738905240   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:11:55 T:1920713320  NOTICE: thread end: video_thread
19:11:55 T:1920713320   DEBUG: Thread DVDPlayerVideo 1920713320 terminating
19:11:55 T:1920657104  NOTICE: deleting video codec
19:11:55 T:1920657104   DEBUG: CAMLCodec::CloseDecoder
19:11:55 T:1920341200   DEBUG: CAMLCodec::Process Stopped
19:11:55 T:1920341200   DEBUG: Thread CAMLCodec 1920341200 terminating
19:11:55 T:1738722768    INFO: CActiveAESink::OpenSink - initialize sink
19:11:55 T:1738722768   DEBUG: CActiveAESink::OpenSink - trying to open device AUDIOTRACK:AudioTrack
19:11:55 T:1738722768   DEBUG: CActiveAESink::OpenSink - AUDIOTRACK Initialized:
19:11:55 T:1738722768   DEBUG:   Output Device : android
19:11:55 T:1738722768   DEBUG:   Sample Rate   : 44100
19:11:55 T:1738722768   DEBUG:   Sample Format : AE_FMT_S16NE
19:11:55 T:1738722768   DEBUG:   Channel Count : 2
19:11:55 T:1738722768   DEBUG:   Channel Layout: FL,FR
19:11:55 T:1738722768   DEBUG:   Frames        : 1881
19:11:55 T:1738722768   DEBUG:   Frame Samples : 3762
19:11:55 T:1738722768   DEBUG:   Frame Size    : 4
19:11:55 T:1738905240   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:11:56 T:1920657104   DEBUG: Previous line repeats 1 times.
19:11:56 T:1920657104  NOTICE: CDVDPlayer::OnExit() deleting demuxer
19:11:56 T:1920657104  NOTICE: CDVDPlayer::OnExit() deleting input stream
19:11:56 T:1921087064   DEBUG: Thread FileCache 1921087064 terminating
19:11:56 T:1920657104   DEBUG: virtual void CApplication::OnPlayBackStopped() : play state was 2, starting 0
19:11:56 T:1920657104   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
19:11:56 T:1920657104   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
19:11:56 T:1747504680  NOTICE: DVDPlayer: finished waiting
19:11:56 T:1747504680   DEBUG: LinuxRendererGL: Cleaning up GL resources
19:11:56 T:1747504680   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
19:11:56 T:1747504680   DEBUG: ------ Window Deinit (VideoOSD.xml) ------
19:11:56 T:1747504680   DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
19:11:56 T:1920657104   DEBUG: Thread DVDPlayer 1920657104 terminating
19:11:56 T:1747504680   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
19:11:56 T:1747504680   DEBUG: ------ Window Init (MyVideoNav.xml) ------
19:11:56 T:1747504680   DEBUG: Window MyVideoNav.xml was already loaded
19:11:56 T:1747504680   DEBUG: Alloc resources: 4.40m
19:11:56 T:1747504680   DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.ardmediathek_de/?hideshowname=True&mode=listVideosRss&name=Typisch!&nextpage=True&showName=Typisch!&url=http%3a%2f%2fwww.ardmediathek.de%2ftv%2fTypisch%2fSendung%3fdocumentId%3d14049256%26bcastId%3d14049256%26m23644322%3dquelle.tv%26rss%3dtrue)
19:11:56 T:1747504680   DEBUG:   ParentPath = [plugin://plugin.video.ardmediathek_de/?hideshowname=True&mode=listVideosRss&name=Typisch!&nextpage=True&showName=Typisch!&url=http%3a%2f%2fwww.ardmediathek.de%2ftv%2fTypisch%2fSendung%3fdocumentId%3d14049256%26bcastId%3d14049256%26m23644322%3dquelle.tv%26rss%3dtrue]
19:11:56 T:1747504680   DEBUG: Loading items: 45, directory: plugin://plugin.video.ardmediathek_de/?hideshowname=True&mode=listVideosRss&name=Typisch!&nextpage=True&showName=Typisch!&url=http%3a%2f%2fwww.ardmediathek.de%2ftv%2fTypisch%2fSendung%3fdocumentId%3d14049256%26bcastId%3d14049256%26m23644322%3dquelle.tv%26rss%3dtrue sort method: 0, ascending: false
19:11:56 T:1747504680   DEBUG: int CVideoDatabase::RunQuery(const CStdString&) took 1 ms for 4 items query: SELECT  files.strFilename, files.playCount,  bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files  LEFT JOIN bookmark ON    files.idFile = bookmark.idFile AND bookmark.type = 1  WHERE files.idPath=1
19:11:56 T:1926034920  NOTICE: Thread BackgroundLoader start, auto delete: false
19:11:56 T:1747504680   DEBUG: Unfocus WindowID: 10025, ControlID: 51
19:11:56 T:1747504680   DEBUG: Keyboard: scancode: 0x71, sym: 0x0132, unicode: 0x0000, modifier: 0x40
19:11:56 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): ctrl-leftctrl (0x1f0d0) pressed, action is
19:11:56 T:1747504680   DEBUG: Keyboard: scancode: 0x43, sym: 0x0008, unicode: 0x0000, modifier: 0x40
19:11:56 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): ctrl-backspace (0x1f008) pressed, action is
19:11:56 T:1747504680   DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
19:11:56 T:1747504680  NOTICE: CDVDPlayer::CloseFile()
19:11:56 T:1747504680  NOTICE: DVDPlayer: waiting for threads to exit
19:11:56 T:1747504680  NOTICE: DVDPlayer: finished waiting
19:11:56 T:1747504680   DEBUG: LinuxRendererGL: Cleaning up GL resources
19:11:56 T:1747504680  NOTICE: CDVDPlayer::CloseFile()
19:11:56 T:1747504680  NOTICE: DVDPlayer: waiting for threads to exit
19:11:56 T:1747504680  NOTICE: DVDPlayer: finished waiting
19:11:56 T:1747504680   DEBUG: LinuxRendererGL: Cleaning up GL resources
19:11:56 T:1926034920   DEBUG: Thread BackgroundLoader 1926034920 terminating
19:11:56 T:1920216312  NOTICE: Thread JobWorker start, auto delete: true
19:11:56 T:1920216312   DEBUG: virtual bool CSaveFileStateJob::DoWork() - Saving file state for video item plugin://plugin.video.ardmediathek_de/?url=http%3A%2F%2Fwww.ardmediathek.de%2Ftv%2FTypisch%2FMach-weiter-Elfriede%2FNDR-Fernsehen%2FVideo%3FdocumentId%3D18607920%26amp%3BbcastId%3D14049256&mode=playVideoUrl
19:11:58 T:1747504680   DEBUG: bool CApplication::ProcessMouse(): trying mouse action leftclick
19:11:58 T:1747504680   DEBUG: Activating window ID: 10000
19:11:59 T:1747504680   DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
19:11:59 T:1747504680   DEBUG: ------ Window Init (Home.xml) ------
19:11:59 T:1747504680   DEBUG: Window Home.xml was already loaded
19:11:59 T:1747504680   DEBUG: Alloc resources: 7.67m
19:11:59 T:1747504680   DEBUG: Unfocus WindowID: 10000, ControlID: 9000
19:12:00 T:1738905240   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:12:00 T:1747504680   DEBUG: Keyboard: scancode: 0x16, sym: 0x0113, unicode: 0x0000, modifier: 0x0
19:12:00 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): right (0xf083) pressed, action is Right
19:12:01 T:1747504680   DEBUG: Keyboard: scancode: 0x16, sym: 0x0113, unicode: 0x0000, modifier: 0x0
19:12:01 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): right (0xf083) pressed, action is Right
19:12:02 T:1747504680   DEBUG: Keyboard: scancode: 0x16, sym: 0x0113, unicode: 0x0000, modifier: 0x0
19:12:02 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): right (0xf083) pressed, action is Right
19:12:03 T:1747504680   DEBUG: Keyboard: scancode: 0x42, sym: 0x000d, unicode: 0x000a, modifier: 0x0
19:12:03 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): return (0xf00d) pressed, action is Select
19:12:03 T:1747504680   DEBUG: Activating window ID: 10004
19:12:03 T:1747504680   DEBUG: ------ Window Deinit (Home.xml) ------
19:12:03 T:1747504680   DEBUG: ------ Window Init (Settings.xml) ------
19:12:03 T:1747504680    INFO: Loading skin file: Settings.xml, load type: KEEP_IN_MEMORY
19:12:03 T:1747504680   ERROR: unable to load:/data/data/hk.minix.xbmc/cache/apk/assets/addons/skin.confluence/720p/Settings.xml, Line 0
                                            Failed to open file
19:12:03 T:1747504680   DEBUG: Load Settings.xml: 0.96ms
19:12:03 T:1747504680   DEBUG: Alloc resources: 1.10ms  (1.10 ms skin load)
19:12:10 T:1747504680   DEBUG: Keyboard: scancode: 0x72, sym: 0x0131, unicode: 0x0000, modifier: 0x80
19:12:10 T:1747504680   DEBUG: bool CApplication::OnKey(const CKey&): ctrl-rightctrl (0x1f0d1) pressed, action is