12:44:00.120 T:4378160000 DEBUG: CWinSystemIOS::OnAppFocusChange: 1 12:44:00.161 T:6159151104 NOTICE: special://profile/ is mapped to: special://masterprofile/ 12:44:00.162 T:6159151104 NOTICE: ----------------------------------------------------------------------- 12:44:00.162 T:6159151104 NOTICE: Starting Kodi (17.6 Git:20171114-beba186). Platform: iOS ARM 64-bit 12:44:00.162 T:6159151104 NOTICE: Using Debug Kodi x64 build 12:44:00.163 T:6159151104 NOTICE: Kodi compiled Nov 14 2017 by Clang 6.0 (clang-600.0.56) for iOS ARM 64-bit version 5.1.0 (50100) 12:44:00.163 T:6159151104 NOTICE: Running on Apple Inc. iPad4,1 with iOS 12.1.4, kernel: Darwin ARM 64-bit version 18.2.0 12:44:00.164 T:6159151104 NOTICE: FFmpeg version/source: ffmpeg-3.1-kodi 12:44:00.164 T:6159151104 NOTICE: Host CPU: arm64 v8, 2 cores available 12:44:00.164 T:6159151104 NOTICE: ARM Features: Neon enabled 12:44:00.164 T:6159151104 NOTICE: special://xbmc/ is mapped to: /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome 12:44:00.165 T:6159151104 NOTICE: special://xbmcbin/ is mapped to: /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome 12:44:00.165 T:6159151104 NOTICE: special://xbmcbinaddons/ is mapped to: /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons 12:44:00.167 T:6159151104 NOTICE: special://masterprofile/ is mapped to: /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/userdata 12:44:00.167 T:6159151104 NOTICE: special://envhome/ is mapped to: /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6 12:44:00.168 T:6159151104 NOTICE: special://home/ is mapped to: /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi 12:44:00.168 T:6159151104 NOTICE: special://temp/ is mapped to: /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/temp 12:44:00.168 T:6159151104 NOTICE: special://logpath/ is mapped to: /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents 12:44:00.169 T:6159151104 NOTICE: special://frameworks/ is mapped to: /var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/Frameworks 12:44:00.169 T:6159151104 NOTICE: The executable running is: /var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/Kodi 12:44:00.169 T:6159151104 NOTICE: Local hostname: iPadAir 12:44:00.169 T:6159151104 NOTICE: Log File is located: /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents//kodi.log 12:44:00.170 T:6159151104 NOTICE: ----------------------------------------------------------------------- 12:44:00.170 T:6159151104 NOTICE: load settings... 12:44:00.183 T:6159151104 DEBUG: CSettings: loaded settings definition from special://xbmc/system/settings/settings.xml 12:44:00.185 T:6159151104 WARNING: CSettingInt: unknown options filler "audiocdactions" of "audiocds.autoaction" 12:44:00.193 T:6159151104 WARNING: CSettingString: unknown options filler "timezonecountries" of "locale.timezonecountry" 12:44:00.193 T:6159151104 WARNING: CSettingString: unknown options filler "timezones" of "locale.timezone" 12:44:00.197 T:6159151104 DEBUG: CSettings: loaded settings definition from special://xbmc/system/settings/darwin.xml 12:44:00.200 T:6159151104 DEBUG: CSettings: loaded settings definition from special://xbmc/system/settings/darwin_ios.xml 12:44:00.201 T:6159151104 DEBUG: EnumerateDevices:Device(default) 12:44:00.202 T:6159151104 NOTICE: Found 1 Lists of Devices 12:44:00.203 T:6159151104 NOTICE: Enumerated DARWINIOS devices: 12:44:00.203 T:6159151104 NOTICE: Device 1 12:44:00.203 T:6159151104 NOTICE: m_deviceName : default 12:44:00.204 T:6159151104 NOTICE: m_displayName : Default 12:44:00.204 T:6159151104 NOTICE: m_displayNameExtra: 12:44:00.204 T:6159151104 NOTICE: m_deviceType : AE_DEVTYPE_PCM 12:44:00.204 T:6159151104 NOTICE: m_channels : FL,FR 12:44:00.205 T:6159151104 NOTICE: m_sampleRates : 44100,48000 12:44:00.205 T:6159151104 NOTICE: m_dataFormats : AE_FMT_S16LE,AE_FMT_FLOAT 12:44:00.205 T:6159151104 NOTICE: m_streamTypes : No passthrough capabilities 12:44:00.207 T:6159151104 DEBUG: CWebServer: increasing thread stack to 1048576 12:44:00.211 T:6159151104 DEBUG: CSkinSettings: no tag found 12:44:00.212 T:6159151104 NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml) 12:44:00.214 T:6159151104 NOTICE: Loaded settings file from special://profile/advancedsettings.xml 12:44:00.215 T:6159151104 NOTICE: Contents of special://profile/advancedsettings.xml are... 2 209715200 10 10 4.0 12:44:00.216 T:6159151104 NOTICE: Default Video Player: VideoPlayer 12:44:00.217 T:6159151104 NOTICE: Default Audio Player: paplayer 12:44:00.217 T:6159151104 NOTICE: Disabled debug logging due to GUI setting. Level 0. 12:44:00.218 T:6159151104 NOTICE: Log level changed to "LOG_LEVEL_NORMAL" 12:44:00.218 T:6159151104 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml 12:44:00.220 T:6159151104 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 12:44:00.223 T:6159151104 DEBUG: CPlayerCoreConfig::: created player VideoPlayer 12:44:00.224 T:6159151104 DEBUG: CPlayerCoreConfig::: created player PAPlayer 12:44:00.224 T:6159151104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules 12:44:00.225 T:6159151104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mms/udp 12:44:00.225 T:6159151104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout 12:44:00.226 T:6159151104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp 12:44:00.226 T:6159151104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp 12:44:00.227 T:6159151104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams 12:44:00.227 T:6159151104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp 12:44:00.227 T:6159151104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2 12:44:00.227 T:6159151104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd 12:44:00.228 T:6159151104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage 12:44:00.228 T:6159151104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf 12:44:00.228 T:6159151104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv 12:44:00.229 T:6159151104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio 12:44:00.229 T:6159151104 NOTICE: Loaded playercorefactory configuration 12:44:00.229 T:6159151104 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml. 12:44:00.230 T:6159151104 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping. 12:44:00.240 T:6159151104 INFO: creating subdirectories 12:44:00.241 T:6159151104 INFO: userdata folder: special://masterprofile/ 12:44:00.241 T:6159151104 INFO: recording folder: 12:44:00.241 T:6159151104 INFO: screenshots folder: 12:44:00.252 T:6159151104 NOTICE: Running database version Addons27 12:44:00.253 T:6159151104 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-arm-osx.so) 12:44:00.253 T:6159151104 DEBUG: Loading: /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/system/libcpluff-arm-osx.so 12:44:00.419 T:6161444864 DEBUG: Thread JobWorker start, auto delete: true 12:44:00.427 T:6159151104 NOTICE: ADDONS: Using repository repository.xbmc.org 12:44:00.447 T:6162018304 DEBUG: Thread ActiveAE start, auto delete: false 12:44:00.448 T:6162591744 DEBUG: Thread AESink start, auto delete: false 12:44:00.448 T:6162591744 INFO: CActiveAESink::OpenSink - initialize sink 12:44:00.449 T:6162591744 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default 12:44:00.461 T:6162591744 NOTICE: void CAAudioUnitSink::setCoreAudioPreferredSampleRate() requesting hw samplerate 44100.000000 12:44:00.464 T:6162591744 NOTICE: void CAAudioUnitSink::setCoreAudioBuffersize() setting buffer duration to 0.023220 12:44:00.467 T:6162591744 DEBUG: checkSessionProperties: volume = 0.000000, latency = 0.012766, buffer = 0.023220 12:44:00.467 T:6162591744 NOTICE: bool CAAudioUnitSink::setupAudio() setup audio format: [lpcm] Mixable Interleaved 2 Channel 32-bit Floating Point LE (44100Hz) 12:44:00.533 T:6162591744 DEBUG: CActiveAESink::OpenSink - DARWINIOS Initialized: 12:44:00.533 T:6162591744 DEBUG: Output Device : Default 12:44:00.533 T:6162591744 DEBUG: Sample Rate : 44100 12:44:00.534 T:6162591744 DEBUG: Sample Format : AE_FMT_FLOAT 12:44:00.534 T:6162591744 DEBUG: Channel Count : 2 12:44:00.534 T:6162591744 DEBUG: Channel Layout: FL,FR 12:44:00.534 T:6162591744 DEBUG: Frames : 1024 12:44:00.534 T:6162591744 DEBUG: Frame Size : 8 12:44:00.547 T:6159151104 INFO: CKeyboardLayoutManager: loading keyboard layouts from special://xbmc/system/keyboardlayouts... 12:44:00.549 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BasePY" successfully loaded 12:44:00.550 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BaiduPY" successfully loaded 12:44:00.552 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian AZERTY" successfully loaded 12:44:00.553 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian QWERTY" successfully loaded 12:44:00.555 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Silesian QWERTY" successfully loaded 12:44:00.557 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian ЙЦУКЕН" successfully loaded 12:44:00.558 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian АБВ" successfully loaded 12:44:00.559 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Danish QWERTY" successfully loaded 12:44:00.560 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "French AZERTY" successfully loaded 12:44:00.562 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Arabic QWERTY" successfully loaded 12:44:00.563 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Czech QWERTZ" successfully loaded 12:44:00.565 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Norwegian QWERTY" successfully loaded 12:44:00.566 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "German QWERTZ" successfully loaded 12:44:00.567 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "German ABC" successfully loaded 12:44:00.569 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian ЯВЕРТЪ" successfully loaded 12:44:00.569 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian АБВ" successfully loaded 12:44:00.571 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Swedish QWERTY" successfully loaded 12:44:00.574 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Turkish QWERTY" successfully loaded 12:44:00.577 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Hungarian QWERTZ" successfully loaded 12:44:00.579 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Icelandic QWERTY" successfully loaded 12:44:00.581 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Romanian QWERTY" successfully loaded 12:44:00.583 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Spanish QWERTY" successfully loaded 12:44:00.585 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew QWERTY" successfully loaded 12:44:00.586 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew ABC" successfully loaded 12:44:00.588 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Portuguese (Portugal) QWERTY" successfully loaded 12:44:00.590 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Italian QWERTY" successfully loaded 12:44:00.592 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Greek QWERTY" successfully loaded 12:44:00.593 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Croatian QWERTY" successfully loaded 12:44:00.594 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Polish QWERTY" successfully loaded 12:44:00.596 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTZ" successfully loaded 12:44:00.596 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTY" successfully loaded 12:44:00.597 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak ABC" successfully loaded 12:44:00.599 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Korean ㄱㄴㄷ" successfully loaded 12:44:00.601 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian ЙЦУКЕН" successfully loaded 12:44:00.602 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian АБВ" successfully loaded 12:44:00.603 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "English QWERTY" successfully loaded 12:44:00.604 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "English AZERTY" successfully loaded 12:44:00.605 T:6159151104 DEBUG: CKeyboardLayoutManager: keyboard layout "English ABC" successfully loaded 12:44:00.605 T:6159151104 DEBUG: Current resolution Screen: 0 with 2048 x 1536 12:44:00.606 T:6159151104 NOTICE: Found possible resolution for display 0 with 1536 x 2048 12:44:00.606 T:6159151104 NOTICE: Checking resolution 16 12:44:00.606 T:6159151104 DEBUG: About to switch to 2048 x 1536 on screen 0 12:44:00.607 T:6159151104 DEBUG: Found matching mode 12:44:00.607 T:6159151104 INFO: GLES: Maximum texture width: 2048 12:44:00.608 T:6159151104 DEBUG: EGL_EXTENSIONS: GL_OES_depth_texture GL_OES_depth_texture_cube_map GL_OES_depth24 GL_OES_element_index_uint GL_OES_fbo_render_mipmap GL_OES_mapbuffer GL_OES_packed_depth_stencil GL_OES_rgb8_rgba8 GL_OES_standard_derivatives GL_OES_texture_float GL_OES_texture_half_float GL_OES_texture_half_float_linear GL_OES_vertex_array_object GL_EXT_blend_minmax GL_EXT_color_buffer_half_float GL_EXT_debug_label GL_EXT_debug_marker GL_EXT_discard_framebuffer GL_EXT_draw_instanced GL_EXT_instanced_arrays GL_EXT_map_buffer_range GL_EXT_occlusion_query_boolean GL_EXT_pvrtc_sRGB GL_EXT_read_format_bgra GL_EXT_separate_shader_objects GL_EXT_shader_framebuffer_fetch GL_EXT_shader_texture_lod GL_EXT_shadow_samplers GL_EXT_sRGB GL_EXT_texture_filter_anisotropic GL_EXT_texture_rg GL_EXT_texture_storage GL_APPLE_clip_distance GL_APPLE_color_buffer_packed_float GL_APPLE_copy_texture_levels GL_APPLE_framebuffer_multisample GL_APPLE_rgb_422 GL_APPLE_sync GL_APPLE_texture_format_BGRA8888 GL_APPLE_texture_max_level GL_APPLE_texture_packed_float GL_IMG_read_format GL_IMG_texture_compression_pvrtc 12:44:00.608 T:6159151104 NOTICE: GL_VENDOR = Apple Inc. 12:44:00.609 T:6159151104 NOTICE: GL_RENDERER = Apple A7 GPU 12:44:00.609 T:6159151104 NOTICE: GL_VERSION = OpenGL ES 2.0 Apple A7 GPU - 130.1 12:44:00.609 T:6159151104 NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 1.00 12:44:00.609 T:6159151104 DEBUG: GLES: Extension Support Test - GL_NVX_gpu_memory_info NO 12:44:00.610 T:6159151104 NOTICE: GL_EXTENSIONS = GL_OES_depth_texture GL_OES_depth_texture_cube_map GL_OES_depth24 GL_OES_element_index_uint GL_OES_fbo_render_mipmap GL_OES_mapbuffer GL_OES_packed_depth_stencil GL_OES_rgb8_rgba8 GL_OES_standard_derivatives GL_OES_texture_float GL_OES_texture_half_float GL_OES_texture_half_float_linear GL_OES_vertex_array_object GL_EXT_blend_minmax GL_EXT_color_buffer_half_float GL_EXT_debug_label GL_EXT_debug_marker GL_EXT_discard_framebuffer GL_EXT_draw_instanced GL_EXT_instanced_arrays GL_EXT_map_buffer_range GL_EXT_occlusion_query_boolean GL_EXT_pvrtc_sRGB GL_EXT_read_format_bgra GL_EXT_separate_shader_objects GL_EXT_shader_framebuffer_fetch GL_EXT_shader_texture_lod GL_EXT_shadow_samplers GL_EXT_sRGB GL_EXT_texture_filter_anisotropic GL_EXT_texture_rg GL_EXT_texture_storage GL_APPLE_clip_distance GL_APPLE_color_buffer_packed_float GL_APPLE_copy_texture_levels GL_APPLE_framebuffer_multisample GL_APPLE_rgb_422 GL_APPLE_sync GL_APPLE_texture_format_BGRA8888 GL_APPLE_texture_max_level GL_APPLE_texture_packed_float GL_IMG_read_format GL_IMG_texture_compression_pvrtc 12:44:00.610 T:6159151104 DEBUG: GLES: Extension Support Test - GL_EXT_texture_format_BGRA8888 NO 12:44:00.611 T:6159151104 DEBUG: GLES: Extension Support Test - GL_IMG_texture_format_BGRA8888 NO 12:44:00.611 T:6159151104 DEBUG: GLES: Extension Support Test - GL_APPLE_texture_format_BGRA8888 YES 12:44:00.617 T:6159151104 DEBUG: GL: Vertex Shader compilation log: 12:44:00.618 T:6159151104 DEBUG: GL: Vertex Shader compiled successfully 12:44:00.618 T:6159151104 DEBUG: GL: Pixel Shader compilation log: 12:44:00.619 T:6159151104 DEBUG: GL: Fragment Shader compiled successfully 12:44:00.622 T:6159151104 DEBUG: GUI Shader [guishader_frag_default.glsl]- Initialise successful : 0x28221f2a0 12:44:00.624 T:6159151104 DEBUG: GL: Vertex Shader compilation log: 12:44:00.625 T:6159151104 DEBUG: GL: Vertex Shader compiled successfully 12:44:00.625 T:6159151104 DEBUG: GL: Pixel Shader compilation log: 12:44:00.625 T:6159151104 DEBUG: GL: Fragment Shader compiled successfully 12:44:00.626 T:6159151104 DEBUG: GUI Shader [guishader_frag_texture.glsl]- Initialise successful : 0x28221f9f0 12:44:00.628 T:6159151104 DEBUG: GL: Vertex Shader compilation log: 12:44:00.628 T:6159151104 DEBUG: GL: Vertex Shader compiled successfully 12:44:00.629 T:6159151104 DEBUG: GL: Pixel Shader compilation log: 12:44:00.630 T:6159151104 DEBUG: GL: Fragment Shader compiled successfully 12:44:00.630 T:6159151104 DEBUG: GUI Shader [guishader_frag_multi.glsl]- Initialise successful : 0x282210b40 12:44:00.633 T:6159151104 DEBUG: GL: Vertex Shader compilation log: 12:44:00.633 T:6159151104 DEBUG: GL: Vertex Shader compiled successfully 12:44:00.634 T:6159151104 DEBUG: GL: Pixel Shader compilation log: 12:44:00.634 T:6159151104 DEBUG: GL: Fragment Shader compiled successfully 12:44:00.635 T:6159151104 DEBUG: GUI Shader [guishader_frag_fonts.glsl]- Initialise successful : 0x28221fb10 12:44:00.637 T:6159151104 DEBUG: GL: Vertex Shader compilation log: 12:44:00.637 T:6159151104 DEBUG: GL: Vertex Shader compiled successfully 12:44:00.638 T:6159151104 DEBUG: GL: Pixel Shader compilation log: 12:44:00.638 T:6159151104 DEBUG: GL: Fragment Shader compiled successfully 12:44:00.639 T:6159151104 DEBUG: GUI Shader [guishader_frag_texture_noblend.glsl]- Initialise successful : 0x28221f690 12:44:00.640 T:6159151104 DEBUG: GL: Vertex Shader compilation log: 12:44:00.641 T:6159151104 DEBUG: GL: Vertex Shader compiled successfully 12:44:00.641 T:6159151104 DEBUG: GL: Pixel Shader compilation log: 12:44:00.641 T:6159151104 DEBUG: GL: Fragment Shader compiled successfully 12:44:00.642 T:6159151104 DEBUG: GUI Shader [guishader_frag_multi_blendcolor.glsl]- Initialise successful : 0x28221fd50 12:44:00.643 T:6159151104 DEBUG: GL: Vertex Shader compilation log: 12:44:00.644 T:6159151104 DEBUG: GL: Vertex Shader compiled successfully 12:44:00.644 T:6159151104 DEBUG: GL: Pixel Shader compilation log: 12:44:00.644 T:6159151104 DEBUG: GL: Fragment Shader compiled successfully 12:44:00.646 T:6159151104 DEBUG: GUI Shader [guishader_frag_rgba.glsl]- Initialise successful : 0x282210d80 12:44:00.646 T:6159151104 DEBUG: GLES: Extension Support Test - GL_OES_EGL_image_external NO 12:44:00.648 T:6159151104 DEBUG: GL: Vertex Shader compilation log: 12:44:00.649 T:6159151104 DEBUG: GL: Vertex Shader compiled successfully 12:44:00.650 T:6159151104 DEBUG: GL: Pixel Shader compilation log: 12:44:00.650 T:6159151104 DEBUG: GL: Fragment Shader compiled successfully 12:44:00.651 T:6159151104 DEBUG: GUI Shader [guishader_frag_rgba_blendcolor.glsl]- Initialise successful : 0x282214240 12:44:00.653 T:6159151104 DEBUG: GL: Vertex Shader compilation log: 12:44:00.653 T:6159151104 DEBUG: GL: Vertex Shader compiled successfully 12:44:00.654 T:6159151104 DEBUG: GL: Pixel Shader compilation log: 12:44:00.654 T:6159151104 DEBUG: GL: Fragment Shader compiled successfully 12:44:00.655 T:6159151104 DEBUG: GUI Shader [guishader_frag_rgba_bob.glsl]- Initialise successful : 0x282214360 12:44:00.655 T:6159151104 DEBUG: GLES: Extension Support Test - GL_OES_EGL_image_external NO 12:44:00.655 T:6159151104 DEBUG: About to switch to 2048 x 1536 on screen 0 12:44:00.656 T:6159151104 DEBUG: Found matching mode 12:44:00.656 T:6159151104 INFO: GLES: Maximum texture width: 4096 12:44:00.827 T:6159151104 INFO: GLES: Enabling VSYNC 12:44:00.827 T:6159151104 INFO: GLES: Selected vsync mode 10 12:44:00.837 T:6159151104 INFO: load keymapping 12:44:00.841 T:6159151104 INFO: Loading special://xbmc/system/keymaps/appcommand.xml 12:44:00.842 T:6159151104 INFO: Loading special://xbmc/system/keymaps/customcontroller.AppleRemote.xml 12:44:00.844 T:6159151104 INFO: Loading special://xbmc/system/keymaps/customcontroller.Harmony.xml 12:44:00.847 T:6159151104 INFO: Loading special://xbmc/system/keymaps/gamepad.xml 12:44:00.852 T:6159151104 INFO: Loading special://xbmc/system/keymaps/joystick.xml 12:44:00.858 T:6159151104 INFO: Loading special://xbmc/system/keymaps/keyboard.xml 12:44:00.865 T:6159151104 INFO: Loading special://xbmc/system/keymaps/mouse.xml 12:44:00.867 T:6159151104 INFO: Loading special://xbmc/system/keymaps/remote.xml 12:44:00.873 T:6159151104 INFO: Loading special://xbmc/system/keymaps/touchscreen.xml 12:44:00.876 T:6159151104 INFO: GUI format 2048x1536, Display 2048x1536 12:44:00.876 T:6159151104 DEBUG: guilib: Fill viewport on change for solving rendering passes 12:44:00.877 T:6159151104 INFO: CLangInfo: loading resource.language.de_de language information... 12:44:00.878 T:6159151104 DEBUG: trying to set locale to de_DE.UTF-8 12:44:00.880 T:6159151104 INFO: global locale set to C 12:44:00.880 T:6159151104 INFO: CLangInfo: loading resource.language.de_de language strings... 12:44:00.894 T:6159151104 DEBUG: LocalizeStrings: loaded 3768 strings from file resource://resource.language.de_de/strings.po 12:44:00.906 T:6159151104 DEBUG: LocalizeStrings: loaded 12 strings from file resource://resource.language.en_gb/strings.po 12:44:00.934 T:6159151104 DEBUG: LocalizeStrings: loaded 1 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/audiodecoder.timidity/resources/language/English/strings.po 12:44:00.940 T:6159151104 DEBUG: LocalizeStrings: loaded 6 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/audioencoder.lame/resources/language/English/strings.po 12:44:00.944 T:6159151104 DEBUG: LocalizeStrings: loaded 6 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/audioencoder.vorbis/resources/language/English/strings.po 12:44:00.950 T:6159151104 DEBUG: LocalizeStrings: loaded 1 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/audioencoder.xbmc.builtin.aac/resources/language/English/strings.po 12:44:00.954 T:6159151104 DEBUG: LocalizeStrings: loaded 1 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/audioencoder.xbmc.builtin.wma/resources/language/English/strings.po 12:44:00.957 T:6159151104 DEBUG: LocalizeStrings: loaded 18 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/game.controller.default/resources/language/resource.language.en_gb/strings.po 12:44:00.960 T:6159151104 DEBUG: LocalizeStrings: loaded 10 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/inputstream.adaptive/resources/language/resource.language.en_gb/strings.po 12:44:00.966 T:6159151104 DEBUG: LocalizeStrings: loaded 11 strings from file /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/metadata.album.universal/resources/language/German/strings.po 12:44:00.968 T:6159151104 DEBUG: LocalizeStrings: loaded 2 strings from file /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/metadata.album.universal/resources/language/English/strings.po 12:44:00.970 T:6159151104 DEBUG: LocalizeStrings: loaded 23 strings from file /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/metadata.artists.universal/resources/language/German/strings.po 12:44:00.973 T:6159151104 DEBUG: LocalizeStrings: loaded 3 strings from file /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/metadata.artists.universal/resources/language/English/strings.po 12:44:00.997 T:6159151104 DEBUG: LocalizeStrings: loaded 7 strings from file /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/metadata.themoviedb.org/resources/language/resource.language.de_de/strings.po 12:44:00.998 T:6159151104 DEBUG: POParser: id:30003 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead 12:44:00.998 T:6159151104 DEBUG: LocalizeStrings: loaded 2 strings from file /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/metadata.themoviedb.org/resources/language/resource.language.en_gb/strings.po 12:44:01.001 T:6159151104 DEBUG: LocalizeStrings: loaded 5 strings from file /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/metadata.tvdb.com/resources/language/German/strings.po 12:44:01.003 T:6159151104 DEBUG: LocalizeStrings: loaded 5 strings from file /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/metadata.tvdb.com/resources/language/English/strings.po 12:44:01.007 T:6159151104 DEBUG: LocalizeStrings: loaded 8 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.argustv/resources/language/resource.language.de_de/strings.po 12:44:01.009 T:6159151104 DEBUG: LocalizeStrings: loaded 0 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.argustv/resources/language/resource.language.en_gb/strings.po 12:44:01.013 T:6159151104 DEBUG: LocalizeStrings: loaded 67 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.dvblink/resources/language/resource.language.de_de/strings.po 12:44:01.015 T:6159151104 DEBUG: LocalizeStrings: loaded 0 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.dvblink/resources/language/resource.language.en_gb/strings.po 12:44:01.017 T:6159151104 DEBUG: LocalizeStrings: loaded 43 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.dvbviewer/resources/language/resource.language.de_de/strings.po 12:44:01.018 T:6159151104 DEBUG: LocalizeStrings: loaded 5 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.dvbviewer/resources/language/resource.language.en_gb/strings.po 12:44:01.020 T:6159151104 DEBUG: LocalizeStrings: loaded 3 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.filmon/resources/language/resource.language.de_de/strings.po 12:44:01.021 T:6159151104 DEBUG: LocalizeStrings: loaded 0 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.filmon/resources/language/resource.language.en_gb/strings.po 12:44:01.023 T:6159151104 DEBUG: LocalizeStrings: loaded 5 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.hdhomerun/resources/language/resource.language.de_de/strings.po 12:44:01.024 T:6159151104 DEBUG: LocalizeStrings: loaded 0 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.hdhomerun/resources/language/resource.language.en_gb/strings.po 12:44:01.026 T:6159151104 DEBUG: LocalizeStrings: loaded 74 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.hts/resources/language/resource.language.de_de/strings.po 12:44:01.027 T:6159151104 DEBUG: LocalizeStrings: loaded 1 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.hts/resources/language/resource.language.en_gb/strings.po 12:44:01.029 T:6159151104 DEBUG: LocalizeStrings: loaded 22 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.iptvsimple/resources/language/resource.language.de_de/strings.po 12:44:01.030 T:6159151104 DEBUG: LocalizeStrings: loaded 0 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.iptvsimple/resources/language/resource.language.en_gb/strings.po 12:44:01.032 T:6159151104 DEBUG: LocalizeStrings: loaded 71 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.mediaportal.tvserver/resources/language/resource.language.de_de/strings.po 12:44:01.033 T:6159151104 DEBUG: LocalizeStrings: loaded 0 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.mediaportal.tvserver/resources/language/resource.language.en_gb/strings.po 12:44:01.036 T:6159151104 DEBUG: LocalizeStrings: loaded 92 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.mythtv/resources/language/resource.language.de_de/strings.po 12:44:01.038 T:6159151104 DEBUG: LocalizeStrings: loaded 11 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.mythtv/resources/language/resource.language.en_gb/strings.po 12:44:01.039 T:6159151104 DEBUG: LocalizeStrings: loaded 28 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.nextpvr/resources/language/resource.language.de_de/strings.po 12:44:01.041 T:6159151104 DEBUG: LocalizeStrings: loaded 0 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.nextpvr/resources/language/resource.language.en_gb/strings.po 12:44:01.043 T:6159151104 DEBUG: LocalizeStrings: loaded 2 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.njoy/resources/language/resource.language.de_de/strings.po 12:44:01.044 T:6159151104 DEBUG: LocalizeStrings: loaded 0 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.njoy/resources/language/resource.language.en_gb/strings.po 12:44:01.047 T:6159151104 DEBUG: LocalizeStrings: loaded 2 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.octonet/resources/language/resource.language.en_gb/strings.po 12:44:01.049 T:6159151104 DEBUG: LocalizeStrings: loaded 8 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.pctv/resources/language/resource.language.de_de/strings.po 12:44:01.050 T:6159151104 DEBUG: LocalizeStrings: loaded 0 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.pctv/resources/language/resource.language.en_gb/strings.po 12:44:01.052 T:6159151104 DEBUG: LocalizeStrings: loaded 43 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.stalker/resources/language/resource.language.de_de/strings.po 12:44:01.053 T:6159151104 DEBUG: LocalizeStrings: loaded 0 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.stalker/resources/language/resource.language.en_gb/strings.po 12:44:01.055 T:6159151104 DEBUG: LocalizeStrings: loaded 25 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.vbox/resources/language/resource.language.de_de/strings.po 12:44:01.056 T:6159151104 DEBUG: LocalizeStrings: loaded 0 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.vbox/resources/language/resource.language.en_gb/strings.po 12:44:01.058 T:6159151104 DEBUG: LocalizeStrings: loaded 69 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.vdr.vnsi/resources/language/resource.language.de_de/strings.po 12:44:01.060 T:6159151104 DEBUG: LocalizeStrings: loaded 1 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.vdr.vnsi/resources/language/resource.language.en_gb/strings.po 12:44:01.061 T:6159151104 DEBUG: LocalizeStrings: loaded 27 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.vuplus/resources/language/resource.language.de_de/strings.po 12:44:01.063 T:6159151104 DEBUG: LocalizeStrings: loaded 0 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.vuplus/resources/language/resource.language.en_gb/strings.po 12:44:01.064 T:6159151104 DEBUG: LocalizeStrings: loaded 70 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.wmc/resources/language/resource.language.de_de/strings.po 12:44:01.066 T:6159151104 DEBUG: LocalizeStrings: loaded 0 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/pvr.wmc/resources/language/resource.language.en_gb/strings.po 12:44:01.082 T:6159151104 DEBUG: LocalizeStrings: loaded 1 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/screensaver.xbmc.builtin.dim/resources/language/resource.language.de_de/strings.po 12:44:01.083 T:6159151104 DEBUG: LocalizeStrings: loaded 0 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/screensaver.xbmc.builtin.dim/resources/language/resource.language.en_gb/strings.po 12:44:01.089 T:6159151104 DEBUG: LocalizeStrings: loaded 21 strings from file /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/service.xbmc.versioncheck/resources/language/German/strings.po 12:44:01.091 T:6159151104 DEBUG: LocalizeStrings: loaded 3 strings from file /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/service.xbmc.versioncheck/resources/language/English/strings.po 12:44:01.100 T:6159151104 DEBUG: LocalizeStrings: loaded 15 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/visualization.spectrum/resources/language/German/strings.po 12:44:01.103 T:6159151104 DEBUG: LocalizeStrings: loaded 0 strings from file /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/addons/visualization.spectrum/resources/language/English/strings.po 12:44:01.119 T:6159151104 DEBUG: LocalizeStrings: loaded 1 strings from file /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/plugin.program.advancedsettings/resources/language/english/strings.xml 12:44:01.121 T:6159151104 DEBUG: SECTION:LoadDLL(libcurl.4.dylib) 12:44:01.122 T:6159151104 DEBUG: Loading: /var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/Frameworks/libcurl.4.dylib 12:44:01.147 T:6161444864 NOTICE: Running database version Addons27 12:44:01.147 T:6161444864 DEBUG: Initialize, updating databases... 12:44:01.151 T:6161444864 NOTICE: Running database version ViewModes6 12:44:01.154 T:6161444864 NOTICE: Running database version Textures13 12:44:01.161 T:6161444864 NOTICE: Running database version MyMusic60 12:44:01.170 T:6161444864 NOTICE: Running database version MyVideos107 12:44:01.175 T:6161444864 NOTICE: Running database version TV29 12:44:01.179 T:6161444864 NOTICE: Running database version Epg11 12:44:01.183 T:6161444864 DEBUG: Initialize, updating databases... DONE 12:44:01.195 T:6159151104 INFO: DPMS: not supported on this platform 12:44:01.246 T:6160297984 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 12:44:01.246 T:6160297984 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 12:44:01.270 T:6159151104 INFO: Unloading old skin ... 12:44:01.273 T:6159151104 INFO: load skin from: /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/skin.confluence (version: 3.1.43) 12:44:01.275 T:6159151104 INFO: load fonts for skin... 12:44:01.278 T:6159151104 INFO: Loading fonts from /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/skin.confluence/720p/Font.xml 12:44:01.322 T:6159151104 DEBUG: LocalizeStrings: loaded 141 strings from file /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/skin.confluence/language/resource.language.de_de/strings.po 12:44:01.325 T:6159151104 DEBUG: LocalizeStrings: loaded 3 strings from file /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/skin.confluence/language/resource.language.en_gb/strings.po 12:44:01.327 T:6159151104 INFO: Loading skin includes from /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/skin.confluence/720p/Includes.xml 12:44:01.397 T:6159151104 INFO: load new skin... 12:44:01.397 T:6159151104 INFO: Loading user windows, path /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/skin.confluence/720p 12:44:01.411 T:6159151104 DEBUG: Load Skin XML: 14.43ms 12:44:01.411 T:6159151104 INFO: initialize new skin... 12:44:01.412 T:6159151104 DEBUG: guilib: Fill viewport on change for solving rendering passes 12:44:01.412 T:6159151104 INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT 12:44:01.413 T:6159151104 DEBUG: Load Pointer.xml: 1.26ms 12:44:01.420 T:6159151104 DEBUG: OpenBundle - Opened bundle /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/skin.confluence/media/Textures.xbt 12:44:01.421 T:6159151104 DEBUG: Alloc resources: 9.29ms (1.71 ms skin load) 12:44:01.421 T:6159151104 INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT 12:44:01.424 T:6159151104 DEBUG: Load DialogVolumeBar.xml: 2.02ms 12:44:01.424 T:6159151104 DEBUG: Alloc resources: 2.90ms (2.36 ms skin load) 12:44:01.425 T:6159151104 INFO: Loading skin file: DialogNotification.xml, load type: LOAD_ON_GUI_INIT 12:44:01.426 T:6159151104 DEBUG: Load DialogNotification.xml: 1.48ms 12:44:01.426 T:6159151104 DEBUG: Alloc resources: 1.81ms (1.81 ms skin load) 12:44:01.427 T:6159151104 INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT 12:44:01.432 T:6159151104 DEBUG: Load DialogSeekBar.xml: 4.94ms 12:44:01.433 T:6159151104 DEBUG: Alloc resources: 6.38ms (5.39 ms skin load) 12:44:01.433 T:6159151104 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT 12:44:01.435 T:6159151104 DEBUG: Load DialogBusy.xml: 1.52ms 12:44:01.435 T:6159151104 DEBUG: Alloc resources: 1.86ms (1.85 ms skin load) 12:44:01.435 T:6159151104 INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT 12:44:01.437 T:6159151104 DEBUG: Load DialogExtendedProgressBar.xml: 1.49ms 12:44:01.437 T:6159151104 DEBUG: Alloc resources: 1.83ms (1.82 ms skin load) 12:44:01.437 T:6159151104 DEBUG: Alloc resources: 0.00ms (0.00 ms skin load) 12:44:01.440 T:6159151104 INFO: Loading resource://resource.uisounds.kodi/sounds.xml 12:44:01.469 T:6159151104 INFO: skin loaded... 12:44:01.469 T:6159151104 DEBUG: Activating window ID: 12997 12:44:01.469 T:6159151104 DEBUG: ------ Window Init () ------ 12:44:01.470 T:6159151104 DEBUG: Alloc resources: 0.00ms (0.00 ms skin load) 12:44:01.470 T:6159151104 INFO: load splash image: /private/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/AppData/AppHome/media/Splash.png 12:44:01.486 T:6159151104 DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting 12:44:01.487 T:6159151104 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList" 12:44:01.487 T:6159151104 INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting") 12:44:01.488 T:6159151104 INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting 12:44:01.513 T:6159151104 INFO: JSONRPC v8.0.0: Successfully initialized 12:44:01.514 T:6159151104 DEBUG: ADDON: Starting service addons. 12:44:01.520 T:6159151104 DEBUG: Activating window ID: 12999 12:44:01.520 T:6159151104 DEBUG: ------ Window Init (Startup.xml) ------ 12:44:01.520 T:6159151104 INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME 12:44:01.520 T:6164312064 DEBUG: Thread LanguageInvoker start, auto delete: false 12:44:01.521 T:6164312064 INFO: initializing python engine. 12:44:01.521 T:6164312064 DEBUG: PYTHONHOME -> /var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/Frameworks/ 12:44:01.521 T:6164312064 DEBUG: PYTHONPATH -> /var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/Frameworks/ 12:44:01.522 T:6159151104 DEBUG: Load Startup.xml: 0.91ms 12:44:01.522 T:6159151104 DEBUG: Alloc resources: 1.69ms (1.69 ms skin load) 12:44:01.522 T:6159151104 DEBUG: Activating window ID: 10000 12:44:01.522 T:6159151104 DEBUG: ------ Window Deinit (Startup.xml) ------ 12:44:01.522 T:6159151104 DEBUG: ------ Window Init (Home.xml) ------ 12:44:01.523 T:6159151104 INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY 12:44:01.561 T:6159151104 DEBUG: Load Home.xml: 37.98ms 12:44:01.561 T:6159151104 DEBUG: CDirectoryProvider[library://video/movies/]: refreshing.. 12:44:01.574 T:6159151104 DEBUG: CDirectoryProvider[library://video/tvshows/]: refreshing.. 12:44:01.574 T:6164885504 DEBUG: Thread JobWorker start, auto delete: true 12:44:01.583 T:6159151104 DEBUG: CDirectoryProvider[library://music/]: refreshing.. 12:44:01.584 T:6161444864 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultCountry.png' 12:44:01.588 T:6161444864 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultGenre.png' 12:44:01.589 T:6161444864 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultYear.png' 12:44:01.590 T:6161444864 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultStudios.png' 12:44:01.591 T:6161444864 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultDirector.png' 12:44:01.593 T:6161444864 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultMovieTitle.png' 12:44:01.595 T:6161444864 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultTags.png' 12:44:01.595 T:6165458944 DEBUG: Thread JobWorker start, auto delete: true 12:44:01.596 T:6164885504 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultGenre.png' 12:44:01.604 T:6164885504 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultYear.png' 12:44:01.608 T:6164885504 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultStudios.png' 12:44:01.611 T:6164885504 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultTVShowTitle.png' 12:44:01.612 T:6164885504 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'DefaultTags.png' 12:44:01.643 T:6159151104 DEBUG: Alloc resources: 119.63ms (38.46 ms skin load) 12:44:01.644 T:6164885504 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update 12:44:01.652 T:6159151104 DEBUG: ContextMenuManager: addon menus reloaded. 12:44:01.654 T:6159151104 INFO: removing tempfiles 12:44:01.656 T:6164885504 DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.*, songartistview.* FROM (SELECT idAlbum FROM album ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum = recentalbums.idAlbum JOIN songartistview ON songview.idSong = songartistview.idSong ORDER BY songview.idAlbum DESC, songview.idSong, songartistview.idRole, songartistview.iOrder 12:44:01.657 T:6164885504 DEBUG: GetRecentlyAddedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM album WHERE strAlbum != '' ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN albumview ON albumview.idAlbum = recentalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY albumview.idAlbum desc, albumartistview.iOrder 12:44:01.660 T:6164885504 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update 12:44:01.665 T:6159151104 DEBUG: ADDON: Starting service addons. 12:44:01.675 T:6164312064 DEBUG: CPythonInvoker(0, /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/service.xbmc.versioncheck/service.py): start processing 12:44:01.676 T:6164885504 DEBUG: RunQuery took 1 ms for 0 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 10 12:44:01.678 T:6164885504 DEBUG: RunQuery took 1 ms for 0 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 10 12:44:01.679 T:6164885504 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view ORDER BY dateAdded desc, idMVideo desc LIMIT 10 12:44:01.682 T:6164885504 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update 12:44:01.686 T:6164885504 DEBUG: GetArtistsByWhere query: SELECT COUNT(DISTINCT artistview.idArtist) FROM artistview WHERE ((EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artistview.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '')) AND (artistview.strArtist <> 'Verschiedene Interpreten') 12:44:01.696 T:6159151104 DEBUG: CRepositoryUpdater: previous update at 24.03.2019 12:21:32, next at 25.03.2019 12:21:32 12:44:01.697 T:6166032384 DEBUG: Thread Timer start, auto delete: false 12:44:01.698 T:6159151104 NOTICE: initialize done 12:44:01.698 T:6159151104 NOTICE: Running the application... 12:44:01.700 T:6159151104 DEBUG: no profile autoexec.py (/private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/userdata/autoexec.py) found, skipping 12:44:01.700 T:6159151104 DEBUG: NetworkMessage - Starting network services 12:44:01.701 T:6159151104 NOTICE: starting zeroconf publishing 12:44:01.702 T:6159151104 NOTICE: CWebServer[8080]: Started 12:44:01.703 T:6159151104 NOTICE: starting upnp client 12:44:01.703 T:6164885504 DEBUG: CZeroconfOSX::doPublishService identifier: servers.webserver type: _http._tcp name:Kodi (iPadAir) port:8080 12:44:01.704 T:6165458944 DEBUG: CZeroconfOSX::doPublishService identifier: servers.jsonrpc-http type: _xbmc-jsonrpc-h._tcp name:Kodi (iPadAir) port:8080 12:44:01.742 T:6172241920 DEBUG: Thread EventServer start, auto delete: false 12:44:01.743 T:6172241920 NOTICE: ES: Starting UDP Event server on port 9777 12:44:01.743 T:6172241920 NOTICE: UDP: Listening on port 9777 (ipv6 : false) 12:44:01.743 T:6164885504 DEBUG: CZeroconfOSX::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:Kodi (iPadAir) port:9777 12:44:01.745 T:6159151104 INFO: JSONRPC Server: Successfully initialized 12:44:01.745 T:6172815360 DEBUG: Thread TCPServer start, auto delete: false 12:44:01.745 T:6165458944 DEBUG: CZeroconfOSX::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:Kodi (iPadAir) port:9090 12:44:01.757 T:6164312064 DEBUG: -->Python Interpreter Initialized<-- 12:44:01.758 T:6164312064 DEBUG: CPythonInvoker(0, /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/service.xbmc.versioncheck/service.py): the source file to load is "/private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/service.xbmc.versioncheck/service.py" 12:44:01.758 T:6164312064 DEBUG: CPythonInvoker(0, /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/service.xbmc.versioncheck/service.py): setting the Python path to /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/service.xbmc.versioncheck:/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/Frameworks/lib/python2.7/site-packages/setuptools-18.4-py2.7.egg:/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/Frameworks/lib/python2.7/site-packages/Pillow-3.0.0-py2.7-macosx-10.4-x86_64.egg:/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/Frameworks:/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/Frameworks/lib/python27.zip:/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/Frameworks/lib/python2.7:/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/Frameworks/lib/python2.7/plat-darwin:/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/Frameworks/lib/python2.7/plat-mac:/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/Frameworks/lib/python2.7/plat-mac/lib-scriptpackages:/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/Frameworks/lib/python2.7/lib-tk:/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/Frameworks/lib/python2.7/lib-old:/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/Frameworks/lib/python2.7/lib-dynload:/var/containers/Bundle/Application/4466F0F0-4FF1-4878-892F-143AD90C242E/Kodi.app/Frameworks/lib/python2.7/site-packages 12:44:01.759 T:6164312064 DEBUG: CPythonInvoker(0, /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/service.xbmc.versioncheck/service.py): entering source directory /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/service.xbmc.versioncheck 12:44:01.760 T:6164312064 DEBUG: CPythonInvoker(0, /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/service.xbmc.versioncheck/service.py): instantiating addon using automatically obtained id of "service.xbmc.versioncheck" dependent on version 2.1.0 of the xbmc.python api 12:44:01.811 T:6164312064 DEBUG: Version Check: Version 0.4.6 started 12:44:03.226 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0114, unicode: 0x0000, modifier: 0x0 12:44:03.227 T:6159151104 DEBUG: OnKey: left (0xf082) pressed, action is Left 12:44:03.228 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0114, unicode: 0x0000, modifier: 0x0 12:44:03.351 T:6159151104 DEBUG: Previous line repeats 1 times. 12:44:03.351 T:6159151104 DEBUG: OnKey: left (0xf082) pressed, action is Left 12:44:03.352 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0114, unicode: 0x0000, modifier: 0x0 12:44:03.956 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:03.956 T:6159151104 DEBUG: OnKey: down (0xf081) pressed, action is Down 12:44:03.957 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:04.488 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:04.488 T:6159151104 DEBUG: OnKey: return (0xf00d) pressed, action is Select 12:44:04.491 T:6159151104 DEBUG: Activating window ID: 10025 12:44:04.739 T:6159151104 DEBUG: ------ Window Deinit (Home.xml) ------ 12:44:04.785 T:6159151104 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 12:44:04.786 T:6159151104 INFO: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY 12:44:04.853 T:6159151104 DEBUG: Load MyVideoNav.xml: 66.59ms 12:44:04.858 T:6159151104 DEBUG: Alloc resources: 72.17ms (67.12 ms skin load) 12:44:04.859 T:6159151104 DEBUG: CGUIMediaWindow::GetDirectory (sources://video/) 12:44:04.859 T:6159151104 DEBUG: ParentPath = [sources://video/] 12:44:04.867 T:6173388800 DEBUG: Thread BackgroundLoader start, auto delete: false 12:44:04.879 T:6173388800 DEBUG: Thread BackgroundLoader 6173388800 terminating 12:44:04.880 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:05.929 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:05.929 T:6159151104 DEBUG: OnKey: down (0xf081) pressed, action is Down 12:44:05.930 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:06.396 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:06.396 T:6159151104 DEBUG: OnKey: return (0xf00d) pressed, action is Select 12:44:06.398 T:6159151104 DEBUG: CGUIMediaWindow::GetDirectory (smb://192.168.96.98/) 12:44:06.398 T:6159151104 DEBUG: ParentPath = [sources://video/] 12:44:06.857 T:6157430784 DEBUG: Thread BackgroundLoader start, auto delete: false 12:44:06.870 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:06.929 T:6164312064 DEBUG: Version Check: Version installed {u'major': 17, u'tag': u'stable', u'minor': 6, u'revision': u'20171114-beba186'} 12:44:06.930 T:6164312064 DEBUG: Version Check: Version available {u'major': u'17', u'extrainfo': u'final', u'tagversion': u'', u'tag': u'stable', u'addon_support': u'yes', u'minor': u'6', u'revision': u'20171114-a9a7a20'} 12:44:06.930 T:6164312064 DEBUG: Version Check: There is no newer stable available 12:44:06.931 T:6164312064 INFO: CPythonInvoker(0, /private/var/mobile/Containers/Data/Application/040E90D2-D375-4B4F-9198-09D1B0FF0BE6/Documents/Kodi/addons/service.xbmc.versioncheck/service.py): script successfully run 12:44:06.946 T:6164312064 INFO: Python script stopped 12:44:06.946 T:6164312064 DEBUG: Thread LanguageInvoker 6164312064 terminating 12:44:07.651 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:07.652 T:6159151104 DEBUG: OnKey: down (0xf081) pressed, action is Down 12:44:07.653 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:07.687 T:6157430784 DEBUG: Thread BackgroundLoader 6157430784 terminating 12:44:08.605 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:08.606 T:6159151104 DEBUG: OnKey: down (0xf081) pressed, action is Down 12:44:08.606 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:10.274 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:10.275 T:6159151104 DEBUG: OnKey: return (0xf00d) pressed, action is Select 12:44:10.277 T:6159151104 DEBUG: CGUIMediaWindow::GetDirectory (smb://192.168.96.98/TravelairAC_Volume1/) 12:44:10.278 T:6159151104 DEBUG: ParentPath = [smb://192.168.96.98/] 12:44:10.645 T:6157430784 DEBUG: Thread BackgroundLoader start, auto delete: false 12:44:10.656 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:11.025 T:6157430784 DEBUG: Thread BackgroundLoader 6157430784 terminating 12:44:11.620 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:11.620 T:6159151104 DEBUG: OnKey: down (0xf081) pressed, action is Down 12:44:11.621 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:12.125 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:12.126 T:6159151104 DEBUG: OnKey: return (0xf00d) pressed, action is Select 12:44:12.127 T:6159151104 DEBUG: CGUIMediaWindow::GetDirectory (smb://192.168.96.98/TravelairAC_Volume1/Media/) 12:44:12.128 T:6159151104 DEBUG: ParentPath = [smb://192.168.96.98/TravelairAC_Volume1/] 12:44:12.256 T:6157430784 DEBUG: Thread BackgroundLoader start, auto delete: false 12:44:12.267 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:12.975 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:12.976 T:6159151104 DEBUG: OnKey: down (0xf081) pressed, action is Down 12:44:12.976 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:13.096 T:6157430784 DEBUG: Thread BackgroundLoader 6157430784 terminating 12:44:13.152 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:13.153 T:6159151104 DEBUG: OnKey: down (0xf081) pressed, action is Down 12:44:13.154 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:13.318 T:6159151104 DEBUG: Previous line repeats 1 times. 12:44:13.318 T:6159151104 DEBUG: OnKey: down (0xf081) pressed, action is Down 12:44:13.319 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:13.489 T:6159151104 DEBUG: Previous line repeats 1 times. 12:44:13.490 T:6159151104 DEBUG: OnKey: down (0xf081) pressed, action is Down 12:44:13.491 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:13.701 T:6159151104 DEBUG: Previous line repeats 1 times. 12:44:13.702 T:6159151104 DEBUG: OnKey: down (0xf081) pressed, action is Down 12:44:13.702 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:14.848 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0111, unicode: 0x0000, modifier: 0x0 12:44:14.848 T:6159151104 DEBUG: OnKey: up (0xf080) pressed, action is Up 12:44:14.848 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0111, unicode: 0x0000, modifier: 0x0 12:44:15.129 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:15.130 T:6159151104 DEBUG: OnKey: return (0xf00d) pressed, action is Select 12:44:15.132 T:6159151104 DEBUG: CGUIMediaWindow::GetDirectory (smb://192.168.96.98/TravelairAC_Volume1/Media/) 12:44:15.133 T:6159151104 DEBUG: ParentPath = [smb://192.168.96.98/TravelairAC_Volume1/Media/] 12:44:15.213 T:6157430784 DEBUG: Thread BackgroundLoader start, auto delete: false 12:44:15.228 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:15.231 T:6159151104 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'OverlayUnwatched.png' 12:44:15.249 T:6164885504 DEBUG: Previous line repeats 1 times. 12:44:15.250 T:6164885504 DEBUG: DoWork - trying to extract thumb from video file smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo3.mkv 12:44:15.260 T:6157430784 DEBUG: Thread BackgroundLoader 6157430784 terminating 12:44:15.272 T:6164885504 DEBUG: CSMBFile::Open - opened smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo3.mkv, fd=10000 12:44:15.311 T:6164885504 DEBUG: Open - probing detected format [matroska,webm] 12:44:15.325 T:6164885504 DEBUG: Open - avformat_find_stream_info starting 12:44:15.385 T:6164885504 DEBUG: Open - av_find_stream_info finished 12:44:15.390 T:6164885504 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 12:44:15.399 T:6164885504 DEBUG: ScanForExternalSubtitles: END (total time: 9 ms) 12:44:15.401 T:6164885504 DEBUG: FactoryCodec - Video: - Opening 12:44:15.402 T:6164885504 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 12:44:15.404 T:6164885504 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264 12:44:15.404 T:6164885504 DEBUG: FactoryCodec - Video: ff-h264 - Opened 12:44:15.404 T:6164885504 DEBUG: ExtractThumb - seeking to pos 1083989ms (total: 3251968ms) in smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo3.mkv 12:44:15.421 T:6164885504 DEBUG: SeekTime - seek ended up on time 1080040 12:44:15.590 T:6164885504 DEBUG: cached image 'special://masterprofile/Thumbnails/6/66d6d8cf.jpg' size 720x404 12:44:15.638 T:6164885504 DEBUG: CSMBFile::Close closing fd 10000 12:44:15.643 T:6164885504 DEBUG: ExtractThumb - measured 383 ms to extract thumb from file in 20 packets. 12:44:15.728 T:6165458944 DEBUG: DoWork - trying to extract thumb from video file smb://192.168.96.98/TravelairAC_Volume1/Media/STestVideo4.mkv 12:44:15.734 T:6165458944 DEBUG: CSMBFile::Open - opened smb://192.168.96.98/TravelairAC_Volume1/Media/STestVideo4.mkv, fd=10000 12:44:15.755 T:6165458944 DEBUG: Open - probing detected format [matroska,webm] 12:44:15.765 T:6165458944 DEBUG: Open - avformat_find_stream_info starting 12:44:15.820 T:6165458944 DEBUG: Open - av_find_stream_info finished 12:44:15.821 T:6165458944 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 12:44:15.829 T:6165458944 DEBUG: ScanForExternalSubtitles: END (total time: 8 ms) 12:44:15.829 T:6165458944 DEBUG: FactoryCodec - Video: - Opening 12:44:15.830 T:6165458944 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 12:44:15.830 T:6165458944 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264 12:44:15.831 T:6165458944 DEBUG: FactoryCodec - Video: ff-h264 - Opened 12:44:15.831 T:6165458944 DEBUG: ExtractThumb - seeking to pos 1041920ms (total: 3125760ms) in smb://192.168.96.98/TravelairAC_Volume1/Media/STestVideo4.mkv 12:44:15.842 T:6165458944 DEBUG: SeekTime - seek ended up on time 1041760 12:44:15.941 T:6165458944 DEBUG: cached image 'special://masterprofile/Thumbnails/6/68e24d4a.jpg' size 720x404 12:44:15.975 T:6165458944 DEBUG: CSMBFile::Close closing fd 10000 12:44:15.980 T:6165458944 DEBUG: ExtractThumb - measured 252 ms to extract thumb from file in 20 packets. 12:44:16.238 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:16.238 T:6159151104 DEBUG: OnKey: return (0xf00d) pressed, action is Select 12:44:16.239 T:6159151104 DEBUG: CGUIMediaWindow::GetDirectory (smb://192.168.96.98/TravelairAC_Volume1/Media/) 12:44:16.239 T:6159151104 DEBUG: ParentPath = [smb://192.168.96.98/TravelairAC_Volume1/] 12:44:16.362 T:6157430784 DEBUG: Thread BackgroundLoader start, auto delete: false 12:44:16.371 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:16.917 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:16.918 T:6159151104 DEBUG: OnKey: down (0xf081) pressed, action is Down 12:44:16.918 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:16.967 T:6159151104 INFO: Python, unloading python shared library because no scripts are running anymore 12:44:17.086 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:17.088 T:6159151104 DEBUG: OnKey: down (0xf081) pressed, action is Down 12:44:17.088 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:17.102 T:6157430784 DEBUG: Thread BackgroundLoader 6157430784 terminating 12:44:17.301 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:17.301 T:6159151104 DEBUG: OnKey: down (0xf081) pressed, action is Down 12:44:17.301 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:18.039 T:6159151104 DEBUG: Previous line repeats 1 times. 12:44:18.039 T:6159151104 DEBUG: OnKey: down (0xf081) pressed, action is Down 12:44:18.040 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:18.055 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0111, unicode: 0x0000, modifier: 0x0 12:44:18.056 T:6159151104 DEBUG: OnKey: up (0xf080) pressed, action is Up 12:44:18.057 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0111, unicode: 0x0000, modifier: 0x0 12:44:18.731 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:18.732 T:6159151104 DEBUG: OnKey: down (0xf081) pressed, action is Down 12:44:18.732 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:18.752 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0111, unicode: 0x0000, modifier: 0x0 12:44:18.753 T:6159151104 DEBUG: OnKey: up (0xf080) pressed, action is Up 12:44:18.753 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0111, unicode: 0x0000, modifier: 0x0 12:44:19.490 T:6159151104 DEBUG: Previous line repeats 1 times. 12:44:19.490 T:6159151104 DEBUG: OnKey: up (0xf080) pressed, action is Up 12:44:19.491 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0111, unicode: 0x0000, modifier: 0x0 12:44:19.802 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:19.802 T:6159151104 DEBUG: OnKey: return (0xf00d) pressed, action is Select 12:44:19.804 T:6159151104 DEBUG: CGUIMediaWindow::GetDirectory (smb://192.168.96.98/TravelairAC_Volume1/Media/) 12:44:19.805 T:6159151104 DEBUG: ParentPath = [smb://192.168.96.98/TravelairAC_Volume1/Media/] 12:44:19.874 T:6159151104 DEBUG: RunQuery took 2 ms for 2 items query: SELECT files.strFilename, files.playCount, bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files LEFT JOIN bookmark ON files.idFile = bookmark.idFile AND bookmark.type = 1 WHERE files.idPath=3 12:44:19.876 T:6157430784 DEBUG: Thread BackgroundLoader start, auto delete: false 12:44:19.887 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:19.889 T:6159151104 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'OverlayUnwatched.png' 12:44:19.910 T:6157430784 DEBUG: Thread BackgroundLoader 6157430784 terminating 12:44:20.564 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:20.565 T:6159151104 DEBUG: OnKey: down (0xf081) pressed, action is Down 12:44:20.565 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:21.172 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:21.173 T:6159151104 DEBUG: OnKey: return (0xf00d) pressed, action is Select 12:44:21.173 T:6159151104 INFO: Loading skin file: DialogContextMenu.xml, load type: KEEP_IN_MEMORY 12:44:21.178 T:6159151104 DEBUG: Load DialogContextMenu.xml: 4.31ms 12:44:21.180 T:6159151104 DEBUG: Alloc resources: 6.49ms (4.92 ms skin load) 12:44:21.181 T:6159151104 DEBUG: ------ Window Init (DialogContextMenu.xml) ------ 12:44:21.181 T:6159151104 DEBUG: Window DialogContextMenu.xml was already loaded 12:44:21.182 T:6159151104 DEBUG: Alloc resources: 0.04ms 12:44:24.484 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:24.487 T:6159151104 DEBUG: OnKey: down (0xf081) pressed, action is Down 12:44:24.487 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0112, unicode: 0x0000, modifier: 0x0 12:44:24.610 T:6163165184 WARNING: DARWINIOS: underflow (0 vs 8192 bytes) 12:44:25.213 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:25.214 T:6159151104 DEBUG: OnKey: return (0xf00d) pressed, action is Select 12:44:25.214 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:25.421 T:6159151104 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------ 12:44:25.451 T:6159151104 DEBUG: Loading settings for smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv 12:44:25.454 T:6160297984 DEBUG: GetMovieId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv), query = select idMovie from movie where idFile=1 12:44:25.455 T:6160297984 DEBUG: GetEpisodeId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv), query = select idEpisode from episode where idFile=1 12:44:25.457 T:6160297984 DEBUG: GetMusicVideoId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv), query = select idMVideo from musicvideo where idFile=1 12:44:25.460 T:6160297984 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 12:44:25.461 T:6160297984 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 12:44:25.464 T:6159151104 DEBUG: CPlayerCoreFactory::GetPlayers(smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv) 12:44:25.465 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 12:44:25.465 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 12:44:25.465 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 12:44:25.465 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 12:44:25.465 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 12:44:25.465 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 12:44:25.467 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 12:44:25.467 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 12:44:25.467 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 12:44:25.467 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 12:44:25.468 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 12:44:25.468 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 12:44:25.468 T:6159151104 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 12:44:25.468 T:6159151104 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 12:44:25.468 T:6159151104 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 12:44:25.469 T:6159151104 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 12:44:25.469 T:6159151104 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 12:44:25.469 T:6160297984 DEBUG: GetMovieId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv), query = select idMovie from movie where idFile=2 12:44:25.470 T:6160297984 DEBUG: GetEpisodeId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv), query = select idEpisode from episode where idFile=2 12:44:25.471 T:6160297984 DEBUG: GetMusicVideoId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv), query = select idMVideo from musicvideo where idFile=2 12:44:25.473 T:6160297984 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 12:44:25.474 T:6160297984 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 12:44:25.475 T:6159151104 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData 12:44:25.476 T:6159151104 NOTICE: VideoPlayer: Opening: smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv 12:44:25.476 T:6159151104 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 12:44:25.478 T:6159151104 DEBUG: LinuxRendererGL: Cleaning up GL resources 12:44:25.478 T:6157430784 DEBUG: Thread VideoPlayer start, auto delete: false 12:44:25.478 T:6157430784 NOTICE: Creating InputStream 12:44:25.524 T:6157430784 DEBUG: CSMBFile::Open - opened smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv, fd=10000 12:44:25.530 T:6157430784 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 12:44:25.538 T:6157430784 DEBUG: ScanForExternalSubtitles: END (total time: 9 ms) 12:44:25.539 T:6157430784 NOTICE: Creating Demuxer 12:44:25.657 T:6157430784 DEBUG: Open - probing detected format [matroska,webm] 12:44:25.665 T:6157430784 DEBUG: Open - avformat_find_stream_info starting 12:44:25.699 T:6157430784 DEBUG: Open - av_find_stream_info finished 12:44:25.700 T:6157430784 NOTICE: Opening stream: 0 source: 256 12:44:25.701 T:6157430784 NOTICE: Creating video codec with codec id: 28 12:44:25.701 T:6157430784 DEBUG: FactoryCodec - Video: - Opening 12:44:25.701 T:6157430784 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 12:44:25.702 T:6157430784 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264 12:44:25.702 T:6157430784 DEBUG: FactoryCodec - Video: ff-h264 - Opened 12:44:25.703 T:6157430784 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28 12:44:25.703 T:6157430784 NOTICE: Creating video thread 12:44:25.703 T:6157430784 NOTICE: Opening stream: 1 source: 256 12:44:25.703 T:6157430784 NOTICE: Finding audio codec for: 86019 12:44:25.703 T:6157430784 DEBUG: FactoryCodec - Audio: passthrough - Opening 12:44:25.704 T:6157430784 DEBUG: FactoryCodec - Audio: passthrough - Failed 12:44:25.704 T:6158577664 DEBUG: Thread VideoPlayerVideo start, auto delete: false 12:44:25.704 T:6158577664 NOTICE: running thread: video_thread 12:44:25.705 T:6157430784 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 12:44:25.705 T:6157430784 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 12:44:25.706 T:6157430784 NOTICE: Creating audio thread 12:44:25.706 T:6157430784 NOTICE: Opening stream: 3 source: 256 12:44:25.706 T:6157430784 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opening 12:44:25.706 T:6157430784 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opened 12:44:25.707 T:6160871424 DEBUG: Thread VideoPlayerAudio start, auto delete: false 12:44:25.707 T:6160871424 NOTICE: running thread: CVideoPlayerAudio::Process() 12:44:25.708 T:6157430784 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv 12:44:25.708 T:6157430784 DEBUG: OnPlayBackStarted: play state was 1, starting 1 12:44:25.708 T:6157430784 DEBUG: CVideoPlayer::SetCaching - caching state 2 12:44:25.709 T:6157430784 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 12:44:25.709 T:6159151104 DEBUG: PlayFile: OpenFile succeed, play state 2 12:44:25.709 T:6159151104 DEBUG: OnPlayBackStarted: play state was 2, starting 0 12:44:25.710 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:44:25.711 T:6158577664 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 12:44:25.711 T:6158577664 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 12:44:25.720 T:6160871424 DEBUG: CDVDAudio::Pause - pausing audio stream 12:44:25.723 T:6160871424 NOTICE: Creating audio stream (codec id: 86019, channels: 6, sample rate: 48000, no pass-through) 12:44:25.732 T:6160871424 DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback 12:44:25.763 T:6158577664 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vtb 12:44:25.772 T:6162591744 INFO: CActiveAESink::OpenSink - initialize sink 12:44:25.788 T:6165458944 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv) 12:44:25.797 T:6165458944 DEBUG: GetMovieId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv), query = select idMovie from movie where idFile=1 12:44:25.798 T:6165458944 DEBUG: GetEpisodeId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv), query = select idEpisode from episode where idFile=1 12:44:25.798 T:6165458944 DEBUG: GetMusicVideoId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv), query = select idMVideo from musicvideo where idFile=1 12:44:25.810 T:6160297984 DEBUG: GetMovieId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv), query = select idMovie from movie where idFile=1 12:44:25.811 T:6160297984 DEBUG: GetEpisodeId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv), query = select idEpisode from episode where idFile=1 12:44:25.813 T:6160297984 DEBUG: GetMusicVideoId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv), query = select idMVideo from musicvideo where idFile=1 12:44:25.823 T:6160297984 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 12:44:25.831 T:6160297984 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 12:44:25.847 T:6158577664 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 12:44:25.862 T:6162591744 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default 12:44:25.879 T:6162591744 NOTICE: void CAAudioUnitSink::setCoreAudioPreferredSampleRate() requesting hw samplerate 48000.000000 12:44:25.890 T:6158577664 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 12:44:25.931 T:6158577664 DEBUG: CRenderManager::Configure - change configuration. 1280x720. display: 1280x720. framerate: 25.00. format: BGRA 12:44:25.937 T:6159151104 DEBUG: DeleteRenderer - deleting renderer 12:44:25.938 T:6159151104 DEBUG: LinuxRendererGL: Cleaning up GL resources 12:44:25.941 T:6159151104 DEBUG: Previous line repeats 1 times. 12:44:25.943 T:6159151104 NOTICE: Using GL_TEXTURE_2D 12:44:25.944 T:6159151104 NOTICE: GL: Using CVBREF render method 12:44:25.949 T:6159151104 DEBUG: GL: Requested render method: 0 12:44:25.949 T:6159151104 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 12:44:25.957 T:6159151104 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_YV12 12:44:25.965 T:6159151104 DEBUG: Previous line repeats 1 times. 12:44:25.966 T:6159151104 DEBUG: GL: Vertex Shader compilation log: 12:44:25.966 T:6159151104 DEBUG: GL: Vertex Shader compiled successfully 12:44:25.967 T:6159151104 DEBUG: GL: Pixel Shader compilation log: 12:44:25.967 T:6159151104 DEBUG: GL: Fragment Shader compiled successfully 12:44:25.969 T:6159151104 DEBUG: GL: Vertex Shader compilation log: 12:44:25.969 T:6159151104 DEBUG: GL: Vertex Shader compiled successfully 12:44:25.970 T:6159151104 DEBUG: GL: Pixel Shader compilation log: 12:44:25.970 T:6159151104 DEBUG: GL: Fragment Shader compiled successfully 12:44:25.971 T:6159151104 NOTICE: GL: NPOT texture support detected 12:44:25.972 T:6159151104 DEBUG: CRenderManager::Configure - 4 12:44:25.982 T:6159151104 DEBUG: Activating window ID: 12005 12:44:25.985 T:6159151104 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 12:44:25.987 T:6159151104 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 12:44:25.987 T:6159151104 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY 12:44:25.988 T:6157430784 DEBUG: CVideoPlayer::HandleMessages - player started 2 12:44:26.000 T:6159151104 DEBUG: Load VideoFullScreen.xml: 12.50ms 12:44:26.000 T:6159151104 DEBUG: Alloc resources: 12.96ms (12.93 ms skin load) 12:44:26.016 T:6162591744 NOTICE: void CAAudioUnitSink::setCoreAudioBuffersize() setting buffer duration to 0.021333 12:44:26.022 T:6162591744 DEBUG: checkSessionProperties: volume = 0.000000, latency = 0.012729, buffer = 0.021333 12:44:26.022 T:6162591744 NOTICE: bool CAAudioUnitSink::setupAudio() setup audio format: [lpcm] Mixable Interleaved 2 Channel 16-bit Signed Integer LE (48000Hz) 12:44:26.073 T:6173388800 WARNING: DARWINIOS: underflow (0 vs 4096 bytes) 12:44:26.158 T:6162591744 DEBUG: CActiveAESink::OpenSink - DARWINIOS Initialized: 12:44:26.165 T:6162591744 DEBUG: Output Device : Default 12:44:26.166 T:6162591744 DEBUG: Sample Rate : 48000 12:44:26.166 T:6162591744 DEBUG: Sample Format : AE_FMT_S16NE 12:44:26.169 T:6162591744 DEBUG: Channel Count : 2 12:44:26.170 T:6162591744 DEBUG: Channel Layout: FL,FR 12:44:26.172 T:6162591744 DEBUG: Frames : 1024 12:44:26.172 T:6162591744 DEBUG: Frame Size : 4 12:44:26.175 T:6162018304 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 12:44:26.192 T:6157430784 DEBUG: Previous line repeats 1 times. 12:44:26.195 T:6157430784 DEBUG: CVideoPlayer::HandleMessages - player started 1 12:44:26.195 T:6157430784 DEBUG: CVideoPlayer::SetCaching - caching state 3 12:44:26.209 T:6157430784 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 12:44:26.209 T:6157430784 DEBUG: CVideoPlayer::SetCaching - caching state 0 12:44:26.209 T:6157430784 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 12:44:26.210 T:6157430784 DEBUG: VideoPlayer::Sync - Audio - pts: 96000.000000, cache: 273750.965456, totalcache: 485333.323479 12:44:26.210 T:6157430784 DEBUG: VideoPlayer::Sync - Video - pts: 0.000000, cache: 50000.000000, totalcache: 100000.000000 12:44:26.212 T:6160871424 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-177750.965456) 12:44:26.228 T:6160871424 DEBUG: CDVDAudio::Resume - resume audio stream 12:44:26.231 T:6162018304 DEBUG: ActiveAE - start sync of audio stream 12:44:26.237 T:6158577664 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-177750.965456) 12:44:26.238 T:6158577664 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 12:44:26.333 T:6162018304 DEBUG: ActiveAE::SyncStream - average error of 10.021354, start adjusting 12:44:26.333 T:6162018304 DEBUG: ActiveAE::SyncStream - average error 0.021354 below threshold of 30.000000 12:44:26.342 T:6158577664 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 12:44:26.627 T:6158577664 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 40000 12:44:31.029 T:6158577664 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000 12:44:55.806 T:6161444864 DEBUG: Thread JobWorker 6161444864 terminating (autodelete) 12:44:55.806 T:6164885504 DEBUG: Thread JobWorker 0 terminating (autodelete) 12:50:16.188 T:6157430784 DEBUG: Previous line repeats 1 times. 12:50:16.190 T:6157430784 ERROR: Read - Error( -1, 22, Invalid argument ) - Retrying 12:50:16.192 T:6157430784 DEBUG: Could not resolve \Media\TBBT\tvs-tbbt-dd51-ded-dl-7p-azhd-x264-1210.mkv 12:50:16.193 T:6157430784 ERROR: Read - Error( -1, 2, No such file or directory ) 12:50:16.193 T:6157430784 DEBUG: Could not resolve \Media\TBBT\tvs-tbbt-dd51-ded-dl-7p-azhd-x264-1210.mkv 12:50:16.194 T:6157430784 ERROR: Read - Error( -1, 2, No such file or directory ) 12:50:16.194 T:6157430784 DEBUG: Could not resolve \Media\TBBT\tvs-tbbt-dd51-ded-dl-7p-azhd-x264-1210.mkv 12:50:16.195 T:6157430784 ERROR: Read - Error( -1, 2, No such file or directory ) 12:50:24.062 T:6160871424 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_EOF 12:50:24.302 T:6157430784 INFO: Process - eof reading from demuxer 12:50:24.303 T:6157430784 NOTICE: CVideoPlayer::OnExit() 12:50:24.303 T:6157430784 NOTICE: VideoPlayer: eof, waiting for queues to empty 12:50:24.304 T:6157430784 NOTICE: Closing stream player 1 12:50:24.304 T:6157430784 NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty 12:50:24.305 T:6160871424 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 12:50:24.305 T:6157430784 NOTICE: Waiting for audio thread to exit 12:50:24.306 T:6160871424 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true 12:50:24.306 T:6160871424 NOTICE: thread end: CVideoPlayerAudio::OnExit() 12:50:24.306 T:6160871424 DEBUG: Thread VideoPlayerAudio 6160871424 terminating 12:50:24.307 T:6157430784 NOTICE: Closing audio device 12:50:24.469 T:6162018304 DEBUG: CActiveAE::DiscardStream - audio stream deleted 12:50:24.470 T:6162018304 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 12:50:24.472 T:6157430784 DEBUG: Previous line repeats 2 times. 12:50:24.473 T:6157430784 NOTICE: Deleting audio codec 12:50:24.473 T:6157430784 NOTICE: Closing stream player 2 12:50:24.474 T:6157430784 NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty 12:50:24.474 T:6158577664 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 12:50:24.475 T:6157430784 NOTICE: waiting for video thread to exit 12:50:24.475 T:6158577664 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true 12:50:24.475 T:6158577664 NOTICE: thread end: video_thread 12:50:24.476 T:6158577664 DEBUG: Thread VideoPlayerVideo 6158577664 terminating 12:50:24.476 T:6157430784 NOTICE: deleting video codec 12:50:24.496 T:6157430784 NOTICE: Closing stream player 3 12:50:24.501 T:6157430784 DEBUG: CSMBFile::Close closing fd 10000 12:50:24.501 T:6157430784 DEBUG: Could not resolve \Media\TestVideo1.mkv 12:50:24.502 T:6157430784 DEBUG: OnPlayBackEnded: play state was 2, starting 0 12:50:24.503 T:6157430784 DEBUG: Thread VideoPlayer 6157430784 terminating 12:50:24.516 T:6159151104 DEBUG: DoWork - Saving file state for video item smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv 12:50:24.518 T:6160297984 DEBUG: GetMovieId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv), query = select idMovie from movie where idFile=1 12:50:24.519 T:6160297984 DEBUG: GetEpisodeId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv), query = select idEpisode from episode where idFile=1 12:50:24.521 T:6160297984 DEBUG: GetMusicVideoId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo1.mkv), query = select idMVideo from musicvideo where idFile=1 12:50:24.529 T:6160297984 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 12:50:24.536 T:6160297984 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 12:50:24.609 T:6160297984 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 12:50:24.609 T:6160297984 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 12:50:24.632 T:6159151104 DEBUG: Loading settings for smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv 12:50:24.638 T:6159151104 DEBUG: CPlayerCoreFactory::GetPlayers(smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv) 12:50:24.639 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 12:50:24.639 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 12:50:24.639 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 12:50:24.639 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 12:50:24.639 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 12:50:24.640 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 12:50:24.640 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 12:50:24.640 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 12:50:24.640 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 12:50:24.640 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 12:50:24.640 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 12:50:24.640 T:6159151104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 12:50:24.641 T:6159151104 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 12:50:24.641 T:6159151104 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 12:50:24.641 T:6159151104 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 12:50:24.641 T:6159151104 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 12:50:24.641 T:6159151104 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 12:50:24.641 T:6159151104 NOTICE: CVideoPlayer::CloseFile() 12:50:24.641 T:6159151104 NOTICE: VideoPlayer: waiting for threads to exit 12:50:24.642 T:6159151104 NOTICE: VideoPlayer: finished waiting 12:50:24.642 T:6159151104 DEBUG: DeleteRenderer - deleting renderer 12:50:24.651 T:6159151104 DEBUG: LinuxRendererGL: Cleaning up GL resources 12:50:24.651 T:6159151104 NOTICE: VideoPlayer: Opening: smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv 12:50:24.651 T:6159151104 DEBUG: LinuxRendererGL: Cleaning up GL resources 12:50:24.652 T:6157430784 DEBUG: Thread VideoPlayer start, auto delete: false 12:50:24.652 T:6157430784 NOTICE: Creating InputStream 12:50:24.851 T:6162591744 INFO: CActiveAESink::OpenSink - initialize sink 12:50:24.936 T:6173388800 WARNING: DARWINIOS: underflow (3972 vs 4096 bytes) 12:50:24.970 T:6162591744 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default 12:50:24.984 T:6162591744 NOTICE: void CAAudioUnitSink::setCoreAudioPreferredSampleRate() requesting hw samplerate 44100.000000 12:50:25.153 T:6159151104 DEBUG: ------ Window Init (DialogBusy.xml) ------ 12:50:25.153 T:6159151104 DEBUG: Window DialogBusy.xml was already loaded 12:50:25.154 T:6159151104 DEBUG: Alloc resources: 0.01ms 12:50:25.198 T:6157430784 DEBUG: CSMBFile::Open - opened smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv, fd=10000 12:50:25.205 T:6157430784 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 12:50:25.216 T:6157430784 DEBUG: ScanForExternalSubtitles: END (total time: 11 ms) 12:50:25.216 T:6157430784 NOTICE: Creating Demuxer 12:50:25.236 T:6157430784 DEBUG: Open - probing detected format [matroska,webm] 12:50:25.247 T:6157430784 DEBUG: Open - avformat_find_stream_info starting 12:50:25.289 T:6157430784 DEBUG: Open - av_find_stream_info finished 12:50:25.290 T:6157430784 NOTICE: Opening stream: 0 source: 256 12:50:25.290 T:6157430784 NOTICE: Creating video codec with codec id: 28 12:50:25.290 T:6157430784 DEBUG: FactoryCodec - Video: - Opening 12:50:25.291 T:6157430784 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 12:50:25.291 T:6157430784 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264 12:50:25.291 T:6157430784 DEBUG: FactoryCodec - Video: ff-h264 - Opened 12:50:25.292 T:6157430784 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28 12:50:25.297 T:6157430784 NOTICE: Creating video thread 12:50:25.298 T:6157430784 NOTICE: Opening stream: 1 source: 256 12:50:25.298 T:6157430784 NOTICE: Finding audio codec for: 86019 12:50:25.298 T:6157430784 DEBUG: FactoryCodec - Audio: passthrough - Opening 12:50:25.298 T:6157430784 DEBUG: FactoryCodec - Audio: passthrough - Failed 12:50:25.299 T:6157430784 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 12:50:25.299 T:6161444864 DEBUG: Thread VideoPlayerVideo start, auto delete: false 12:50:25.299 T:6161444864 NOTICE: running thread: video_thread 12:50:25.300 T:6157430784 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 12:50:25.300 T:6157430784 NOTICE: Creating audio thread 12:50:25.301 T:6157430784 NOTICE: Opening stream: 3 source: 256 12:50:25.301 T:6157430784 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opening 12:50:25.301 T:6157430784 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opened 12:50:25.301 T:6157430784 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv 12:50:25.302 T:6157430784 DEBUG: OnPlayBackStarted: play state was 1, starting 1 12:50:25.302 T:6157430784 DEBUG: CVideoPlayer::SetCaching - caching state 2 12:50:25.302 T:6157430784 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 12:50:25.303 T:6163165184 DEBUG: Thread VideoPlayerAudio start, auto delete: false 12:50:25.303 T:6163165184 NOTICE: running thread: CVideoPlayerAudio::Process() 12:50:25.303 T:6163165184 DEBUG: CDVDAudio::Pause - pausing audio stream 12:50:25.304 T:6161444864 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 12:50:25.304 T:6161444864 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 12:50:25.305 T:6163165184 NOTICE: Creating audio stream (codec id: 86019, channels: 6, sample rate: 48000, no pass-through) 12:50:25.315 T:6159151104 DEBUG: PlayFile: OpenFile succeed, play state 2 12:50:25.315 T:6159151104 DEBUG: OnPlayBackStarted: play state was 2, starting 0 12:50:25.334 T:6163738624 DEBUG: Thread JobWorker start, auto delete: true 12:50:25.334 T:6163738624 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv) 12:50:25.347 T:6160297984 DEBUG: GetMovieId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv), query = select idMovie from movie where idFile=2 12:50:25.349 T:6160297984 DEBUG: GetEpisodeId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv), query = select idEpisode from episode where idFile=2 12:50:25.352 T:6160297984 DEBUG: GetMusicVideoId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv), query = select idMVideo from musicvideo where idFile=2 12:50:25.354 T:6163738624 DEBUG: GetMovieId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv), query = select idMovie from movie where idFile=2 12:50:25.357 T:6160297984 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 12:50:25.358 T:6163738624 DEBUG: GetEpisodeId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv), query = select idEpisode from episode where idFile=2 12:50:25.359 T:6163738624 DEBUG: GetMusicVideoId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv), query = select idMVideo from musicvideo where idFile=2 12:50:25.361 T:6160297984 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 12:50:25.441 T:6162591744 NOTICE: void CAAudioUnitSink::setCoreAudioBuffersize() setting buffer duration to 0.023220 12:50:25.461 T:6162591744 DEBUG: checkSessionProperties: volume = 0.062500, latency = 0.012766, buffer = 0.023220 12:50:25.461 T:6162591744 NOTICE: bool CAAudioUnitSink::setupAudio() setup audio format: [lpcm] Mixable Interleaved 2 Channel 32-bit Floating Point LE (44100Hz) 12:50:25.462 T:6161444864 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-vtb 12:50:25.474 T:6159151104 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 12:50:25.503 T:6162591744 DEBUG: CActiveAESink::OpenSink - DARWINIOS Initialized: 12:50:25.504 T:6162591744 DEBUG: Output Device : Default 12:50:25.504 T:6162591744 DEBUG: Sample Rate : 44100 12:50:25.504 T:6162591744 DEBUG: Sample Format : AE_FMT_FLOAT 12:50:25.504 T:6162591744 DEBUG: Channel Count : 2 12:50:25.505 T:6162591744 DEBUG: Channel Layout: FL,FR 12:50:25.505 T:6162591744 DEBUG: Frames : 1024 12:50:25.505 T:6162591744 DEBUG: Frame Size : 8 12:50:25.507 T:6162018304 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 12:50:25.508 T:6163165184 DEBUG: Previous line repeats 1 times. 12:50:25.508 T:6163165184 DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback 12:50:25.548 T:6162591744 INFO: CActiveAESink::OpenSink - initialize sink 12:50:25.568 T:6161444864 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 12:50:25.591 T:6161444864 DEBUG: Previous line repeats 1 times. 12:50:25.592 T:6161444864 DEBUG: CRenderManager::Configure - change configuration. 1280x720. display: 1280x720. framerate: 25.00. format: BGRA 12:50:25.623 T:6162591744 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default 12:50:25.626 T:6159151104 DEBUG: DeleteRenderer - deleting renderer 12:50:25.626 T:6159151104 DEBUG: LinuxRendererGL: Cleaning up GL resources 12:50:25.629 T:6159151104 DEBUG: Previous line repeats 1 times. 12:50:25.629 T:6159151104 NOTICE: Using GL_TEXTURE_2D 12:50:25.630 T:6159151104 NOTICE: GL: Using CVBREF render method 12:50:25.631 T:6162591744 NOTICE: void CAAudioUnitSink::setCoreAudioPreferredSampleRate() requesting hw samplerate 48000.000000 12:50:25.637 T:6159151104 DEBUG: GL: Requested render method: 0 12:50:25.637 T:6159151104 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 12:50:25.638 T:6159151104 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_YV12 12:50:25.640 T:6159151104 DEBUG: Previous line repeats 1 times. 12:50:25.640 T:6159151104 DEBUG: GL: Vertex Shader compilation log: 12:50:25.640 T:6159151104 DEBUG: GL: Vertex Shader compiled successfully 12:50:25.640 T:6159151104 DEBUG: GL: Pixel Shader compilation log: 12:50:25.641 T:6159151104 DEBUG: GL: Fragment Shader compiled successfully 12:50:25.642 T:6159151104 DEBUG: GL: Vertex Shader compilation log: 12:50:25.642 T:6159151104 DEBUG: GL: Vertex Shader compiled successfully 12:50:25.642 T:6159151104 DEBUG: GL: Pixel Shader compilation log: 12:50:25.642 T:6159151104 DEBUG: GL: Fragment Shader compiled successfully 12:50:25.643 T:6159151104 NOTICE: GL: NPOT texture support detected 12:50:25.643 T:6159151104 DEBUG: CRenderManager::Configure - 4 12:50:25.643 T:6161444864 DEBUG: CPullupCorrection: pattern lost on diff -358120000.000000, number of losses 1 12:50:25.648 T:6157430784 DEBUG: CVideoPlayer::HandleMessages - player started 2 12:50:26.046 T:6162591744 NOTICE: void CAAudioUnitSink::setCoreAudioBuffersize() setting buffer duration to 0.021333 12:50:26.079 T:6162591744 DEBUG: checkSessionProperties: volume = 0.062500, latency = 0.012729, buffer = 0.021333 12:50:26.082 T:6162591744 NOTICE: bool CAAudioUnitSink::setupAudio() setup audio format: [lpcm] Mixable Interleaved 2 Channel 16-bit Signed Integer LE (48000Hz) 12:50:26.108 T:6162591744 DEBUG: CActiveAESink::OpenSink - DARWINIOS Initialized: 12:50:26.109 T:6162591744 DEBUG: Output Device : Default 12:50:26.109 T:6162591744 DEBUG: Sample Rate : 48000 12:50:26.110 T:6162591744 DEBUG: Sample Format : AE_FMT_S16NE 12:50:26.110 T:6162591744 DEBUG: Channel Count : 2 12:50:26.110 T:6162591744 DEBUG: Channel Layout: FL,FR 12:50:26.112 T:6162591744 DEBUG: Frames : 1024 12:50:26.113 T:6162591744 DEBUG: Frame Size : 4 12:50:26.124 T:6162018304 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 12:50:26.160 T:6157430784 DEBUG: Previous line repeats 1 times. 12:50:26.160 T:6157430784 DEBUG: CVideoPlayer::HandleMessages - player started 1 12:50:26.161 T:6157430784 DEBUG: CVideoPlayer::SetCaching - caching state 3 12:50:26.161 T:6157430784 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 12:50:26.161 T:6157430784 DEBUG: CVideoPlayer::SetCaching - caching state 0 12:50:26.162 T:6157430784 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 12:50:26.162 T:6157430784 DEBUG: VideoPlayer::Sync - Audio - pts: 96000.000000, cache: 247395.840456, totalcache: 485333.323479 12:50:26.162 T:6157430784 DEBUG: VideoPlayer::Sync - Video - pts: 0.000000, cache: 50000.000000, totalcache: 100000.000000 12:50:26.163 T:6163165184 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-151395.840456) 12:50:26.163 T:6163165184 DEBUG: CDVDAudio::Resume - resume audio stream 12:50:26.163 T:6161444864 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-151395.840456) 12:50:26.163 T:6161444864 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 12:50:26.165 T:6162018304 DEBUG: ActiveAE - start sync of audio stream 12:50:26.178 T:6161444864 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 12:50:26.275 T:6162018304 DEBUG: ActiveAE::SyncStream - average error of -4.729274, start adjusting 12:50:26.275 T:6162018304 DEBUG: ActiveAE::SyncStream - average error -0.729274 below threshold of 30.000000 12:50:26.561 T:6161444864 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 40000 12:50:27.324 T:6163165184 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-13685.459346, adjusted:-13685.459346 12:50:28.355 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:50:28.356 T:6159151104 DEBUG: OnKey: return (0xf00d) pressed, action is OSD 12:50:28.357 T:6159151104 DEBUG: ------ Window Init (VideoOSD.xml) ------ 12:50:28.357 T:6159151104 INFO: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY 12:50:28.380 T:6159151104 DEBUG: Load VideoOSD.xml: 22.48ms 12:50:28.394 T:6159151104 DEBUG: Alloc resources: 37.17ms (22.97 ms skin load) 12:50:29.632 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0113, unicode: 0x0000, modifier: 0x0 12:50:29.633 T:6159151104 DEBUG: OnKey: right (0xf083) pressed, action is Right 12:50:29.633 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x0113, unicode: 0x0000, modifier: 0x0 12:50:29.933 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:50:29.934 T:6159151104 DEBUG: OnKey: return (0xf00d) pressed, action is Select 12:50:29.936 T:6159151104 NOTICE: CVideoPlayer::CloseFile() 12:50:29.937 T:6159151104 NOTICE: VideoPlayer: waiting for threads to exit 12:50:29.938 T:6157430784 NOTICE: CVideoPlayer::OnExit() 12:50:29.938 T:6157430784 NOTICE: Closing stream player 1 12:50:29.939 T:6157430784 NOTICE: Waiting for audio thread to exit 12:50:29.945 T:6163165184 NOTICE: thread end: CVideoPlayerAudio::OnExit() 12:50:29.945 T:6163165184 DEBUG: Thread VideoPlayerAudio 6163165184 terminating 12:50:29.945 T:6157430784 NOTICE: Closing audio device 12:50:29.966 T:6157430784 DEBUG: CDVDAudio::Flush - flush audio stream 12:50:29.967 T:6162018304 DEBUG: CActiveAE::DiscardStream - audio stream deleted 12:50:29.969 T:6162018304 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 12:50:29.972 T:6157430784 DEBUG: Previous line repeats 2 times. 12:50:29.972 T:6157430784 NOTICE: Deleting audio codec 12:50:29.973 T:6157430784 NOTICE: Closing stream player 2 12:50:29.973 T:6157430784 NOTICE: waiting for video thread to exit 12:50:29.981 T:6162591744 INFO: CActiveAESink::OpenSink - initialize sink 12:50:29.985 T:6161444864 NOTICE: thread end: video_thread 12:50:29.985 T:6161444864 DEBUG: Thread VideoPlayerVideo 6161444864 terminating 12:50:29.989 T:6157430784 NOTICE: deleting video codec 12:50:29.996 T:6157430784 NOTICE: Closing stream player 3 12:50:29.997 T:6157430784 DEBUG: CSMBFile::Close closing fd 10000 12:50:30.026 T:6157430784 DEBUG: OnPlayBackStopped: play state was 2, starting 0 12:50:30.027 T:6157430784 DEBUG: Thread VideoPlayer 6157430784 terminating 12:50:30.027 T:6159151104 NOTICE: VideoPlayer: finished waiting 12:50:30.027 T:6159151104 DEBUG: DeleteRenderer - deleting renderer 12:50:30.032 T:6160297984 DEBUG: GetMovieId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv), query = select idMovie from movie where idFile=2 12:50:30.033 T:6160297984 DEBUG: GetEpisodeId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv), query = select idEpisode from episode where idFile=2 12:50:30.033 T:6160297984 DEBUG: GetMusicVideoId (smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv), query = select idMVideo from musicvideo where idFile=2 12:50:30.035 T:6160297984 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 12:50:30.039 T:6160297984 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 12:50:30.050 T:6159151104 DEBUG: LinuxRendererGL: Cleaning up GL resources 12:50:30.051 T:6164312064 WARNING: DARWINIOS: underflow (3188 vs 4096 bytes) 12:50:30.057 T:6159151104 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 12:50:30.057 T:6159151104 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 12:50:30.058 T:6159151104 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 12:50:30.067 T:6159151104 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 12:50:30.078 T:6159151104 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 12:50:30.080 T:6162591744 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default 12:50:30.084 T:6159151104 DEBUG: Window MyVideoNav.xml was already loaded 12:50:30.084 T:6159151104 DEBUG: Alloc resources: 4.88ms 12:50:30.084 T:6159151104 DEBUG: CGUIMediaWindow::GetDirectory (smb://192.168.96.98/TravelairAC_Volume1/Media/TBBT/) 12:50:30.084 T:6159151104 DEBUG: ParentPath = [smb://192.168.96.98/TravelairAC_Volume1/Media/TBBT/] 12:50:30.091 T:6162591744 NOTICE: void CAAudioUnitSink::setCoreAudioPreferredSampleRate() requesting hw samplerate 44100.000000 12:50:30.139 T:6162591744 NOTICE: void CAAudioUnitSink::setCoreAudioBuffersize() setting buffer duration to 0.023220 12:50:30.398 T:6159151104 DEBUG: RunQuery took 0 ms for 2 items query: SELECT files.strFilename, files.playCount, bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files LEFT JOIN bookmark ON files.idFile = bookmark.idFile AND bookmark.type = 1 WHERE files.idPath=3 12:50:30.400 T:6157430784 DEBUG: Thread BackgroundLoader start, auto delete: false 12:50:30.408 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:50:30.425 T:6157430784 DEBUG: Thread BackgroundLoader 6157430784 terminating 12:50:30.489 T:6162591744 DEBUG: checkSessionProperties: volume = 0.062500, latency = 0.012766, buffer = 0.023220 12:50:30.489 T:6162591744 NOTICE: bool CAAudioUnitSink::setupAudio() setup audio format: [lpcm] Mixable Interleaved 2 Channel 32-bit Floating Point LE (44100Hz) 12:50:30.494 T:6159151104 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'OverlayUnwatched.png' 12:50:30.523 T:6162591744 DEBUG: CActiveAESink::OpenSink - DARWINIOS Initialized: 12:50:30.523 T:6162591744 DEBUG: Output Device : Default 12:50:30.524 T:6162591744 DEBUG: Sample Rate : 44100 12:50:30.524 T:6162591744 DEBUG: Sample Format : AE_FMT_FLOAT 12:50:30.524 T:6162591744 DEBUG: Channel Count : 2 12:50:30.524 T:6162591744 DEBUG: Channel Layout: FL,FR 12:50:30.527 T:6162591744 DEBUG: Frames : 1024 12:50:30.528 T:6162591744 DEBUG: Frame Size : 8 12:50:30.538 T:6162018304 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 12:50:30.588 T:6159151104 DEBUG: Previous line repeats 1 times. 12:50:30.589 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:50:30.605 T:6159151104 NOTICE: CVideoPlayer::CloseFile() 12:50:30.606 T:6159151104 NOTICE: VideoPlayer: waiting for threads to exit 12:50:30.606 T:6159151104 NOTICE: VideoPlayer: finished waiting 12:50:30.606 T:6159151104 NOTICE: CVideoPlayer::CloseFile() 12:50:30.606 T:6159151104 NOTICE: VideoPlayer: waiting for threads to exit 12:50:30.606 T:6159151104 NOTICE: VideoPlayer: finished waiting 12:50:30.620 T:6159151104 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 12:50:30.622 T:6163738624 DEBUG: DoWork - Saving file state for video item smb://192.168.96.98/TravelairAC_Volume1/Media/TestVideo2.mkv 12:50:31.695 T:6159151104 DEBUG: Keyboard: scancode: 0x00, sym: 0x000d, unicode: 0x000d, modifier: 0x0 12:50:31.696 T:6159151104 DEBUG: OnKey: return (0xf00d) pressed, action is Select 12:50:31.697 T:6159151104 DEBUG: ------ Window Init (DialogContextMenu.xml) ------ 12:50:31.697 T:6159151104 DEBUG: Window DialogContextMenu.xml was already loaded 12:50:31.698 T:6159151104 DEBUG: Alloc resources: 0.14ms 12:51:00.659 T:6163738624 DEBUG: Thread JobWorker 6163738624 terminating (autodelete)