################################################################################
# ... output of cat /storage/.xbmc/temp/xbmc.log
# OpenELEC release: Generic.x86_64-4.1.1
#
################################################################################
18:24:44 T:139717195147072 NOTICE: special://profile/ is mapped to: special://masterprofile/
18:24:44 T:139717195147072 NOTICE: -----------------------------------------------------------------------
18:24:44 T:139717195147072 NOTICE: Starting XBMC (13.2-BETA1 Git:450924a). Platform: x86 Linux 64-bit
18:24:44 T:139717195147072 NOTICE: Using Release XBMC x64 build, compiled Jul 16 2014 by GCC 4.9.0 for x86 Linux 64-bit 3.15.5
18:24:44 T:139717195147072 NOTICE: Running on Linux 64-bit (OpenELEC (official) - Version: 4.1.1, 3.15.5 x86_64)
18:24:44 T:139717195147072 NOTICE: FFmpeg version: 2.2.4, statically linked: 1
18:24:44 T:139717195147072 NOTICE: WARNING: unsupported ffmpeg version detected
18:24:44 T:139717195147072 NOTICE: Host CPU: Intel(R) Celeron(R) 2955U @ 1.40GHz, 2 cores available
18:24:44 T:139717195147072 NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc
18:24:44 T:139717195147072 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/xbmc
18:24:44 T:139717195147072 NOTICE: special://masterprofile/ is mapped to: /storage/.xbmc/userdata
18:24:44 T:139717195147072 NOTICE: special://home/ is mapped to: /storage/.xbmc
18:24:44 T:139717195147072 NOTICE: special://temp/ is mapped to: /storage/.xbmc/temp
18:24:44 T:139717195147072 NOTICE: The executable running is: /usr/lib/xbmc/xbmc.bin
18:24:44 T:139717195147072 NOTICE: Local hostname: OpenELEC-CB
18:24:44 T:139717195147072 NOTICE: Log File is located: /storage/.xbmc/temp/xbmc.log
18:24:44 T:139717195147072 NOTICE: -----------------------------------------------------------------------
18:24:44 T:139717195147072 ERROR: DBus: Error org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files
18:24:44 T:139717195147072 NOTICE: load settings...
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_LPCM" on device "hdmi:CARD=HDMI,DEV=0" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_DTSHD" on device "hdmi:CARD=HDMI,DEV=0" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_TRUEHD" on device "hdmi:CARD=HDMI,DEV=0" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_EAC3" on device "hdmi:CARD=HDMI,DEV=0" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_DTS" on device "hdmi:CARD=HDMI,DEV=0" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_AC3" on device "hdmi:CARD=HDMI,DEV=0" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_AAC" on device "hdmi:CARD=HDMI,DEV=0" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_LPCM" on device "hdmi:CARD=HDMI,DEV=1" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_DTSHD" on device "hdmi:CARD=HDMI,DEV=1" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_TRUEHD" on device "hdmi:CARD=HDMI,DEV=1" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_EAC3" on device "hdmi:CARD=HDMI,DEV=1" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_DTS" on device "hdmi:CARD=HDMI,DEV=1" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_AC3" on device "hdmi:CARD=HDMI,DEV=1" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_AAC" on device "hdmi:CARD=HDMI,DEV=1" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_LPCM" on device "hdmi:CARD=HDMI,DEV=2" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_DTSHD" on device "hdmi:CARD=HDMI,DEV=2" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_TRUEHD" on device "hdmi:CARD=HDMI,DEV=2" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_EAC3" on device "hdmi:CARD=HDMI,DEV=2" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_DTS" on device "hdmi:CARD=HDMI,DEV=2" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_AC3" on device "hdmi:CARD=HDMI,DEV=2" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: CAESinkALSA::EnumerateDevice data format "AE_FMT_AAC" on device "hdmi:CARD=HDMI,DEV=2" seems to be not supported.
18:24:44 T:139717195147072 NOTICE: Found 1 Lists of Devices
18:24:44 T:139717195147072 NOTICE: Enumerated ALSA devices:
18:24:44 T:139717195147072 NOTICE: Device 1
18:24:44 T:139717195147072 NOTICE: m_deviceName : hdmi:CARD=HDMI,DEV=0
18:24:44 T:139717195147072 NOTICE: m_displayName : HDA Intel
18:24:44 T:139717195147072 NOTICE: m_displayNameExtra: HDMI #0
18:24:44 T:139717195147072 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
18:24:44 T:139717195147072 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR
18:24:44 T:139717195147072 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000
18:24:44 T:139717195147072 NOTICE: m_dataFormats : AE_FMT_LPCM,AE_FMT_DTSHD,AE_FMT_TRUEHD,AE_FMT_EAC3,AE_FMT_DTS,AE_FMT_AC3,AE_FMT_AAC,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE
18:24:44 T:139717195147072 NOTICE: Device 2
18:24:44 T:139717195147072 NOTICE: m_deviceName : hdmi:CARD=HDMI,DEV=1
18:24:44 T:139717195147072 NOTICE: m_displayName : HDA Intel
18:24:44 T:139717195147072 NOTICE: m_displayNameExtra: HDMI #1
18:24:44 T:139717195147072 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
18:24:44 T:139717195147072 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR
18:24:44 T:139717195147072 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000
18:24:44 T:139717195147072 NOTICE: m_dataFormats : AE_FMT_LPCM,AE_FMT_DTSHD,AE_FMT_TRUEHD,AE_FMT_EAC3,AE_FMT_DTS,AE_FMT_AC3,AE_FMT_AAC,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE
18:24:44 T:139717195147072 NOTICE: Device 3
18:24:44 T:139717195147072 NOTICE: m_deviceName : hdmi:CARD=HDMI,DEV=2
18:24:44 T:139717195147072 NOTICE: m_displayName : HDA Intel
18:24:44 T:139717195147072 NOTICE: m_displayNameExtra: HDMI #2
18:24:44 T:139717195147072 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
18:24:44 T:139717195147072 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR
18:24:44 T:139717195147072 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000
18:24:44 T:139717195147072 NOTICE: m_dataFormats : AE_FMT_LPCM,AE_FMT_DTSHD,AE_FMT_TRUEHD,AE_FMT_EAC3,AE_FMT_DTS,AE_FMT_AC3,AE_FMT_AAC,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE
18:24:44 T:139717195147072 NOTICE: Device 4
18:24:44 T:139717195147072 NOTICE: m_deviceName : @:CARD=PCH,DEV=0
18:24:44 T:139717195147072 NOTICE: m_displayName : HDA Intel PCH
18:24:44 T:139717195147072 NOTICE: m_displayNameExtra: ALC283 Analog
18:24:44 T:139717195147072 NOTICE: m_deviceType : AE_DEVTYPE_PCM
18:24:44 T:139717195147072 NOTICE: m_channels : FL,FR
18:24:44 T:139717195147072 NOTICE: m_sampleRates : 48000
18:24:44 T:139717195147072 NOTICE: m_dataFormats : AE_FMT_S32NE
18:24:44 T:139717195147072 NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml
18:24:44 T:139717195147072 NOTICE: Contents of special://xbmc/system/advancedsettings.xml are...
false
cputemp
gputemp
30
4.0
18:24:44 T:139717195147072 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
18:24:44 T:139717195147072 NOTICE: Default DVD Player: dvdplayer
18:24:44 T:139717195147072 NOTICE: Default Video Player: dvdplayer
18:24:44 T:139717195147072 NOTICE: Default Audio Player: paplayer
18:24:44 T:139717195147072 NOTICE: Disabled debug logging due to GUI setting. Level 0.
18:24:44 T:139717195147072 NOTICE: Log level changed to 0
18:24:44 T:139717195147072 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
18:24:44 T:139717195147072 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
18:24:44 T:139717195147072 NOTICE: Loaded playercorefactory configuration
18:24:44 T:139717195147072 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
18:24:44 T:139717195147072 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
18:24:44 T:139717190772480 NOTICE: Thread ActiveAE start, auto delete: false
18:24:44 T:139717182379776 NOTICE: Thread AESink start, auto delete: false
18:24:44 T:139717195147072 NOTICE: Running database version Addons16
18:24:44 T:139717195147072 NOTICE: ADDONS: Using repository repository.xbmc.org
18:24:44 T:139717195147072 NOTICE: ADDONS: Using repository repository.openelec.tv
18:24:44 T:139717098075904 NOTICE: Thread PeripBusCEC start, auto delete: false
18:24:44 T:139717089683200 NOTICE: Thread PeripBusUSBUdev start, auto delete: false
18:24:44 T:139717195147072 NOTICE: Setup SDL
18:24:45 T:139717195147072 NOTICE: Checking resolution 16
18:24:45 T:139717195147072 NOTICE: Using visual 0x20
18:24:45 T:139717195147072 NOTICE: GL_VENDOR = Intel Open Source Technology Center
18:24:45 T:139717195147072 NOTICE: GL_RENDERER = Mesa DRI Intel(R) Haswell Mobile
18:24:45 T:139717195147072 NOTICE: GL_VERSION = 3.0 Mesa 10.2.3
18:24:45 T:139717195147072 NOTICE: GL_SHADING_LANGUAGE_VERSION = 1.30
18:24:45 T:139717195147072 NOTICE: GL_EXTENSIONS = GL_ARB_multisample GL_EXT_abgr GL_EXT_bgra GL_EXT_blend_color GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_copy_texture GL_EXT_polygon_offset GL_EXT_subtexture GL_EXT_texture_object GL_EXT_vertex_array GL_EXT_compiled_vertex_array GL_EXT_texture GL_EXT_texture3D GL_IBM_rasterpos_clip GL_ARB_point_parameters GL_EXT_draw_range_elements GL_EXT_packed_pixels GL_EXT_point_parameters GL_EXT_rescale_normal GL_EXT_separate_specular_color GL_EXT_texture_edge_clamp GL_SGIS_generate_mipmap GL_SGIS_texture_border_clamp GL_SGIS_texture_edge_clamp GL_SGIS_texture_lod GL_ARB_framebuffer_sRGB GL_ARB_multitexture GL_EXT_framebuffer_sRGB GL_IBM_multimode_draw_arrays GL_IBM_texture_mirrored_repeat GL_3DFX_texture_compression_FXT1 GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_transpose_matrix GL_EXT_blend_func_separate GL_EXT_fog_coord GL_EXT_multi_draw_arrays GL_EXT_secondary_color GL_EXT_texture_env_add GL_EXT_texture_filter_anisotropic GL_EXT_texture_lod_bias GL_INGR_blend_func_separate GL_NV_blend_square GL_NV_light_max_exponent GL_NV_texgen_reflection GL_NV_texture_env_combine4 GL_S3_s3tc GL_SUN_multi_draw_arrays GL_ARB_texture_border_clamp GL_ARB_texture_compression GL_EXT_framebuffer_object GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_MESA_window_pos GL_NV_packed_depth_stencil GL_NV_texture_rectangle GL_ARB_depth_texture GL_ARB_occlusion_query GL_ARB_shadow GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_mirrored_repeat GL_ARB_window_pos GL_ATI_envmap_bumpmap GL_EXT_stencil_two_side GL_EXT_texture_cube_map GL_NV_depth_clamp GL_APPLE_packed_pixels GL_APPLE_vertex_array_object GL_ARB_draw_buffers GL_ARB_fragment_program GL_ARB_fragment_shader GL_ARB_shader_objects GL_ARB_vertex_program GL_ARB_vertex_shader GL_ATI_draw_buffers GL_ATI_texture_env_combine3 GL_ATI_texture_float GL_EXT_shadow_funcs GL_EXT_stencil_wrap GL_MESA_pack_invert GL_NV_primitive_restart GL_ARB_depth_clamp GL_ARB_fragment_program_shadow GL_ARB_half_float_pixel GL_ARB_occlusion_query2 GL_ARB_point_sprite GL_ARB_shading_language_100 GL_ARB_sync GL_ARB_texture_non_power_of_two GL_ARB_vertex_buffer_object GL_ATI_blend_equation_separate GL_EXT_blend_equation_separate GL_OES_read_format GL_ARB_color_buffer_float GL_ARB_pixel_buffer_object GL_ARB_texture_compression_rgtc GL_ARB_texture_float GL_ARB_texture_rectangle GL_EXT_packed_float GL_EXT_pixel_buffer_object GL_EXT_texture_compression_dxt1 GL_EXT_texture_compression_rgtc GL_EXT_texture_rectangle GL_EXT_texture_sRGB GL_EXT_texture_shared_exponent GL_ARB_framebuffer_object GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXT_packed_depth_stencil GL_APPLE_object_purgeable GL_ARB_vertex_array_object GL_ATI_separate_stencil GL_EXT_draw_buffers2 GL_EXT_draw_instanced GL_EXT_gpu_program_parameters GL_EXT_texture_array GL_EXT_texture_integer GL_EXT_texture_sRGB_decode GL_EXT_timer_query GL_OES_EGL_image GL_ARB_copy_buffer GL_ARB_depth_buffer_float GL_ARB_draw_instanced GL_ARB_half_float_vertex GL_ARB_instanced_arrays GL_ARB_map_buffer_range GL_ARB_texture_rg GL_ARB_texture_swizzle GL_ARB_vertex_array_bgra GL_EXT_texture_swizzle GL_EXT_vertex_array_bgra GL_NV_conditional_render GL_AMD_conservative_depth GL_AMD_draw_buffers_blend GL_AMD_seamless_cubemap_per_texture GL_ARB_ES2_compatibility GL_ARB_blend_func_extended GL_ARB_debug_output GL_ARB_draw_buffers_blend GL_ARB_draw_elements_base_vertex GL_ARB_explicit_attrib_location GL_ARB_fragment_coord_conventions GL_ARB_provoking_vertex GL_ARB_sample_shading GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_shader_texture_lod GL_ARB_texture_cube_map_array GL_ARB_texture_gather GL_ARB_texture_multisample GL_ARB_texture_query_lod GL_ARB_texture_rgb10_a2ui GL_ARB_uniform_buffer_object GL_ARB_vertex_type_2_10_10_10_rev GL_EXT_provoking_vertex GL_EXT_texture_snorm GL_MESA_texture_signed_rgba GL_ARB_get_program_binary GL_ARB_robustness GL_ARB_separate_shader_objects GL_ARB_shader_bit_encoding GL_ARB_timer_query GL_ANGLE_texture_compression_dxt3 GL_ANGLE_texture_compression_dxt5 GL_ARB_conservative_depth GL_ARB_internalformat_query GL_ARB_map_buffer_alignment GL_ARB_shader_atomic_counters GL_ARB_shading_language_420pack GL_ARB_shading_language_packing GL_ARB_texture_storage GL_EXT_framebuffer_multisample_blit_scaled GL_EXT_transform_feedback GL_AMD_shader_trinary_minmax GL_ARB_ES3_compatibility GL_ARB_clear_buffer_object GL_ARB_invalidate_subdata GL_ARB_texture_query_levels GL_ARB_texture_storage_multisample GL_ARB_texture_view GL_ARB_vertex_attrib_binding GL_KHR_debug GL_ARB_buffer_storage GL_ARB_multi_bind GL_ARB_texture_mirror_clamp_to_edge GL_ARB_vertex_type_10f_11f_11f_rev GL_EXT_shader_integer_mix
18:24:45 T:139717195147072 NOTICE: No Joystick name specified, loading default map
18:24:45 T:139717195147072 NOTICE: Previous line repeats 35 times.
18:24:45 T:139717195147072 NOTICE: Running database version Addons16
18:24:45 T:139717195147072 NOTICE: Running database version ViewModes6
18:24:45 T:139717195147072 NOTICE: Running database version Textures13
18:24:45 T:139717195147072 NOTICE: Running database version MyMusic46
18:24:45 T:139717195147072 NOTICE: Running database version MyVideos78
18:24:45 T:139717195147072 NOTICE: Running database version TV22
18:24:45 T:139717195147072 NOTICE: Running database version Epg7
18:24:45 T:139717195147072 NOTICE: start dvd mediatype detection
18:24:45 T:139717062444800 NOTICE: Thread DetectDVDMedia start, auto delete: false
18:24:45 T:139717195147072 WARNING: JSONRPC: Could not parse type "GUI.Property.Value"
18:24:45 T:139717195147072 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
18:24:45 T:139717195147072 NOTICE: initialize done
18:24:45 T:139717195147072 NOTICE: Running the application...
18:24:45 T:139717050717952 NOTICE: Thread LanguageInvoker start, auto delete: false
18:24:45 T:139717050717952 NOTICE: -->Python Interpreter Initialized<--
18:24:45 T:139717042325248 NOTICE: Thread JobWorker start, auto delete: true
18:24:45 T:139717195147072 NOTICE: starting zeroconf publishing
18:24:45 T:139717195147072 NOTICE: Webserver: Starting...
18:24:45 T:139717195147072 NOTICE: WebServer: Started the webserver
18:24:45 T:139717195147072 NOTICE: starting upnp renderer
18:24:45 T:139716745750272 NOTICE: Thread JobWorker start, auto delete: true
18:24:45 T:139717195147072 NOTICE: ES: Starting event server
18:24:45 T:139716485703424 NOTICE: Thread EventServer start, auto delete: false
18:24:45 T:139716485703424 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
18:24:45 T:139716485703424 NOTICE: UDP: Listening on port 9777
18:24:45 T:139716477310720 NOTICE: Thread TCPServer start, auto delete: false
18:24:45 T:139716460525312 NOTICE: Thread JobWorker start, auto delete: true
18:24:45 T:139716452132608 NOTICE: Thread AirPlayServer start, auto delete: false
18:24:45 T:139717062444800 NOTICE: Thread RSSReader start, auto delete: false
18:24:45 T:139717062444800 ERROR: CCurlFile::FillBuffer - Failed: Couldn't resolve host name(6)
18:24:45 T:139717062444800 ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
18:24:45 T:139717062444800 ERROR: CCurlFile::FillBuffer - Failed: Couldn't resolve host name(6)
18:24:45 T:139717062444800 ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
18:24:45 T:139717062444800 ERROR: CCurlFile::FillBuffer - Failed: Couldn't resolve host name(6)
18:24:45 T:139717062444800 ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
18:24:45 T:139717062444800 ERROR: CCurlFile::FillBuffer - Failed: Couldn't resolve host name(6)
18:24:45 T:139717062444800 ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
18:24:45 T:139717062444800 ERROR: CCurlFile::FillBuffer - Failed: Couldn't resolve host name(6)
18:24:45 T:139717062444800 ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
18:24:45 T:139717062444800 ERROR: CCurlFile::FillBuffer - Failed: Couldn't resolve host name(6)
18:24:45 T:139717062444800 ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
18:24:45 T:139717062444800 ERROR: CCurlFile::FillBuffer - Failed: Couldn't resolve host name(6)
18:24:45 T:139717062444800 ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
18:24:45 T:139717062444800 ERROR: CCurlFile::FillBuffer - Failed: Couldn't resolve host name(6)
18:24:45 T:139717062444800 ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
18:24:45 T:139717062444800 ERROR: CCurlFile::FillBuffer - Failed: Couldn't resolve host name(6)
18:24:45 T:139717062444800 ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
18:24:45 T:139717062444800 ERROR: CCurlFile::FillBuffer - Failed: Couldn't resolve host name(6)
18:24:45 T:139717062444800 ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
18:24:45 T:139717062444800 ERROR: CCurlFile::FillBuffer - Failed: Couldn't resolve host name(6)
18:24:45 T:139717062444800 ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
18:24:45 T:139717062444800 ERROR: CCurlFile::FillBuffer - Failed: Couldn't resolve host name(6)
18:24:45 T:139717062444800 ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
18:24:45 T:139717050717952 NOTICE: ## OpenELEC Addon ## 0.3.19
18:24:57 T:139717195147072 NOTICE: Log level changed to 2
18:24:57 T:139717195147072 NOTICE: Enabled debug logging due to GUI setting. Level 2.
18:24:57 T:139717195147072 DEBUG: ------ Window Init () ------
18:24:58 T:139717195147072 DEBUG: Keyboard: scancode: 0x09, sym: 0x001b, unicode: 0x001b, modifier: 0x0
18:24:58 T:139717195147072 DEBUG: OnKey: escape (0xf01b) pressed, action is PreviousMenu
18:24:58 T:139717195147072 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
18:24:58 T:139717195147072 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------
18:24:58 T:139717195147072 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
18:24:58 T:139717195147072 DEBUG: ------ Window Init (Settings.xml) ------
18:24:59 T:139717195147072 DEBUG: Keyboard: scancode: 0x09, sym: 0x001b, unicode: 0x001b, modifier: 0x0
18:24:59 T:139717195147072 DEBUG: OnKey: escape (0xf01b) pressed, action is PreviousMenu
18:24:59 T:139717195147072 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
18:24:59 T:139717195147072 DEBUG: ------ Window Deinit (Settings.xml) ------
18:24:59 T:139717195147072 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
18:24:59 T:139717195147072 DEBUG: ------ Window Init (Home.xml) ------
18:24:59 T:139717195147072 DEBUG: Keyboard: scancode: 0x71, sym: 0x0114, unicode: 0x0000, modifier: 0x0
18:24:59 T:139717195147072 DEBUG: OnKey: left (0xf082) pressed, action is Left
18:24:59 T:139717195147072 DEBUG: Keyboard: scancode: 0x71, sym: 0x0114, unicode: 0x0000, modifier: 0x0
18:24:59 T:139717195147072 DEBUG: OnKey: left (0xf082) pressed, action is Left
18:25:00 T:139717195147072 DEBUG: Keyboard: scancode: 0x71, sym: 0x0114, unicode: 0x0000, modifier: 0x0
18:25:00 T:139717195147072 DEBUG: OnKey: left (0xf082) pressed, action is Left
18:25:00 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:00 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:01 T:139717195147072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0
18:25:01 T:139717195147072 DEBUG: OnKey: return (0xf00d) pressed, action is Select
18:25:01 T:139717195147072 DEBUG: Activating window ID: 10025
18:25:01 T:139717195147072 DEBUG: ------ Window Deinit (Home.xml) ------
18:25:01 T:139717195147072 DEBUG: ------ Window Init (MyVideoNav.xml) ------
18:25:01 T:139717195147072 INFO: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY
18:25:01 T:139717195147072 DEBUG: CGUIMediaWindow::GetDirectory (sources://video/)
18:25:01 T:139717195147072 DEBUG: ParentPath = [sources://video/]
18:25:01 T:139716007548672 NOTICE: Thread BackgroundLoader start, auto delete: false
18:25:01 T:139716007548672 DEBUG: Thread BackgroundLoader 139716007548672 terminating
18:25:02 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:02 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:02 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:02 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:03 T:139717195147072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0
18:25:03 T:139717195147072 DEBUG: OnKey: return (0xf00d) pressed, action is Select
18:25:03 T:139717195147072 DEBUG: CGUIMediaWindow::GetDirectory (smb://192.168.1.40/media/etc/)
18:25:03 T:139717195147072 DEBUG: ParentPath = [sources://video/]
18:25:03 T:139716745750272 DEBUG: OpenDir - Using authentication url smb://192.168.1.40/media/etc
18:25:03 T:139717195147072 DEBUG: ------ Window Init (DialogBusy.xml) ------
18:25:03 T:139717195147072 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=2
18:25:03 T:139716007548672 NOTICE: Thread BackgroundLoader start, auto delete: false
18:25:03 T:139716007548672 DEBUG: Thread BackgroundLoader 139716007548672 terminating
18:25:03 T:139717195147072 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
18:25:04 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:04 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:04 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:04 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:04 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:04 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:06 T:139717195147072 DEBUG: Keyboard: scancode: 0x09, sym: 0x001b, unicode: 0x001b, modifier: 0x0
18:25:06 T:139717195147072 DEBUG: OnKey: escape (0xf01b) pressed, action is PreviousMenu
18:25:06 T:139717195147072 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
18:25:06 T:139717195147072 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
18:25:06 T:139717195147072 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
18:25:06 T:139717195147072 DEBUG: ------ Window Init (Home.xml) ------
18:25:07 T:139717195147072 DEBUG: Keyboard: scancode: 0x72, sym: 0x0113, unicode: 0x0000, modifier: 0x0
18:25:07 T:139717195147072 DEBUG: OnKey: right (0xf083) pressed, action is Right
18:25:07 T:139717195147072 DEBUG: Keyboard: scancode: 0x72, sym: 0x0113, unicode: 0x0000, modifier: 0x0
18:25:07 T:139717195147072 DEBUG: OnKey: right (0xf083) pressed, action is Right
18:25:08 T:139717195147072 DEBUG: Keyboard: scancode: 0x72, sym: 0x0113, unicode: 0x0000, modifier: 0x0
18:25:08 T:139717195147072 DEBUG: OnKey: right (0xf083) pressed, action is Right
18:25:08 T:139717195147072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0
18:25:08 T:139717195147072 DEBUG: OnKey: return (0xf00d) pressed, action is Select
18:25:08 T:139717195147072 DEBUG: Activating window ID: 10004
18:25:08 T:139717195147072 DEBUG: ------ Window Deinit (Home.xml) ------
18:25:08 T:139717195147072 DEBUG: ------ Window Init (Settings.xml) ------
18:25:09 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:09 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:09 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:09 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:10 T:139717195147072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0
18:25:10 T:139717195147072 DEBUG: OnKey: return (0xf00d) pressed, action is Select
18:25:10 T:139717195147072 DEBUG: Activating window ID: 10017
18:25:10 T:139717195147072 DEBUG: ------ Window Deinit (Settings.xml) ------
18:25:10 T:139717195147072 DEBUG: ------ Window Init (SettingsCategory.xml) ------
18:25:11 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:11 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:11 T:139717195147072 DEBUG: Keyboard: scancode: 0x72, sym: 0x0113, unicode: 0x0000, modifier: 0x0
18:25:11 T:139717195147072 DEBUG: OnKey: right (0xf083) pressed, action is Right
18:25:11 T:139717195147072 DEBUG: Keyboard: scancode: 0x71, sym: 0x0114, unicode: 0x0000, modifier: 0x0
18:25:11 T:139717195147072 DEBUG: OnKey: left (0xf082) pressed, action is Left
18:25:12 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:12 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:12 T:139717195147072 DEBUG: Keyboard: scancode: 0x72, sym: 0x0113, unicode: 0x0000, modifier: 0x0
18:25:12 T:139717195147072 DEBUG: OnKey: right (0xf083) pressed, action is Right
18:25:12 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:12 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:13 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:13 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:13 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:13 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:14 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:14 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:14 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:14 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:17 T:139717195147072 DEBUG: SECTION:UnloadDelayed(DLL: libcrystalhd.so.3)
18:25:17 T:139717195147072 DEBUG: Unloading: libcrystalhd.so.3
18:25:17 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:17 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:17 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:17 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:17 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:17 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:18 T:139717195147072 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86_64-linux.so)
18:25:18 T:139717195147072 DEBUG: Unloading: ImageLib-x86_64-linux.so
18:25:19 T:139717195147072 INFO: CheckIdle - Closing session to http://feeds.openelec.tv (easy=0x7f1230006160, multi=0x7f1230000940)
18:25:19 T:139717195147072 INFO: CheckIdle - Closing session to http://feeds.xbmc.org (easy=0x7f1230020a10, multi=0x7f1230038ff0)
18:25:19 T:139717195147072 DEBUG: Keyboard: scancode: 0x09, sym: 0x001b, unicode: 0x001b, modifier: 0x0
18:25:19 T:139717195147072 DEBUG: OnKey: escape (0xf01b) pressed, action is PreviousMenu
18:25:19 T:139717195147072 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
18:25:20 T:139717195147072 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------
18:25:20 T:139717195147072 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
18:25:20 T:139717195147072 DEBUG: ------ Window Init (Settings.xml) ------
18:25:21 T:139717195147072 DEBUG: Keyboard: scancode: 0x09, sym: 0x001b, unicode: 0x001b, modifier: 0x0
18:25:21 T:139717195147072 DEBUG: OnKey: escape (0xf01b) pressed, action is PreviousMenu
18:25:21 T:139717195147072 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
18:25:21 T:139717195147072 DEBUG: ------ Window Deinit (Settings.xml) ------
18:25:21 T:139717195147072 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
18:25:21 T:139717195147072 DEBUG: ------ Window Init (Home.xml) ------
18:25:21 T:139717195147072 DEBUG: Keyboard: scancode: 0x71, sym: 0x0114, unicode: 0x0000, modifier: 0x0
18:25:21 T:139717195147072 DEBUG: OnKey: left (0xf082) pressed, action is Left
18:25:22 T:139717195147072 DEBUG: Keyboard: scancode: 0x71, sym: 0x0114, unicode: 0x0000, modifier: 0x0
18:25:22 T:139717195147072 DEBUG: OnKey: left (0xf082) pressed, action is Left
18:25:22 T:139717195147072 DEBUG: Keyboard: scancode: 0x71, sym: 0x0114, unicode: 0x0000, modifier: 0x0
18:25:22 T:139717195147072 DEBUG: OnKey: left (0xf082) pressed, action is Left
18:25:22 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:22 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:23 T:139717195147072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0
18:25:23 T:139717195147072 DEBUG: OnKey: return (0xf00d) pressed, action is Select
18:25:23 T:139717195147072 DEBUG: Activating window ID: 10025
18:25:23 T:139717195147072 DEBUG: ------ Window Deinit (Home.xml) ------
18:25:23 T:139717195147072 DEBUG: ------ Window Init (MyVideoNav.xml) ------
18:25:23 T:139717195147072 DEBUG: CGUIMediaWindow::GetDirectory (sources://video/)
18:25:23 T:139717195147072 DEBUG: ParentPath = [sources://video/]
18:25:23 T:139716007548672 NOTICE: Thread BackgroundLoader start, auto delete: false
18:25:23 T:139716007548672 DEBUG: Thread BackgroundLoader 139716007548672 terminating
18:25:24 T:139717195147072 DEBUG: Keyboard: scancode: 0x6f, sym: 0x0111, unicode: 0x0000, modifier: 0x0
18:25:24 T:139717195147072 DEBUG: OnKey: up (0xf080) pressed, action is Up
18:25:24 T:139717195147072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0
18:25:24 T:139717195147072 DEBUG: OnKey: return (0xf00d) pressed, action is Select
18:25:24 T:139717195147072 DEBUG: CGUIMediaWindow::GetDirectory (smb://192.168.1.40/media/etc/)
18:25:24 T:139717195147072 DEBUG: ParentPath = [sources://video/]
18:25:24 T:139716745750272 DEBUG: OpenDir - Using authentication url smb://192.168.1.40/media/etc
18:25:24 T:139717195147072 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=2
18:25:24 T:139716007548672 NOTICE: Thread BackgroundLoader start, auto delete: false
18:25:24 T:139716007548672 DEBUG: Thread BackgroundLoader 139716007548672 terminating
18:25:25 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:25 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:25 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:25 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:25 T:139717195147072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
18:25:25 T:139717195147072 DEBUG: OnKey: down (0xf081) pressed, action is Down
18:25:26 T:139717195147072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0
18:25:26 T:139717195147072 DEBUG: OnKey: return (0xf00d) pressed, action is Select
18:25:26 T:139717195147072 DEBUG: OnPlayMedia smb://192.168.1.40/media/etc/Joe_sample.mkv
18:25:26 T:139717195147072 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
18:25:26 T:139717195147072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
18:25:26 T:139717195147072 DEBUG: GetMovieId (smb://192.168.1.40/media/etc/Joe_sample.mkv), query = select idMovie from movie where idFile=172
18:25:26 T:139717195147072 DEBUG: GetEpisodeId (smb://192.168.1.40/media/etc/Joe_sample.mkv), query = select idEpisode from episode where idFile=172
18:25:26 T:139717195147072 DEBUG: GetMusicVideoId (smb://192.168.1.40/media/etc/Joe_sample.mkv), query = select idMVideo from musicvideo where idFile=172
18:25:26 T:139717195147072 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
18:25:26 T:139717195147072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
18:25:26 T:139717195147072 DEBUG: CPlayerCoreFactory::GetPlayers(smb://192.168.1.40/media/etc/Joe_sample.mkv)
18:25:26 T:139717195147072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
18:25:26 T:139717195147072 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
18:25:26 T:139717195147072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
18:25:26 T:139717195147072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
18:25:26 T:139717195147072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
18:25:26 T:139717195147072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
18:25:26 T:139717195147072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
18:25:26 T:139717195147072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
18:25:26 T:139717195147072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
18:25:26 T:139717195147072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
18:25:26 T:139717195147072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
18:25:26 T:139717195147072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
18:25:26 T:139717195147072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
18:25:26 T:139717195147072 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
18:25:26 T:139717195147072 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
18:25:26 T:139717195147072 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
18:25:26 T:139717195147072 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
18:25:26 T:139717195147072 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
18:25:26 T:139717195147072 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
18:25:26 T:139717195147072 NOTICE: DVDPlayer: Opening: smb://192.168.1.40/media/etc/Joe_sample.mkv
18:25:26 T:139717195147072 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
18:25:26 T:139717195147072 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
18:25:26 T:139717195147072 DEBUG: LinuxRendererGL: Cleaning up GL resources
18:25:26 T:139717195147072 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
18:25:26 T:139716007548672 NOTICE: Thread DVDPlayer start, auto delete: false
18:25:26 T:139716007548672 NOTICE: Creating InputStream
18:25:26 T:139716007548672 DEBUG: CSmbFile::Open - opened media/etc/Joe_sample.mkv, fd=10000
18:25:26 T:139716007548672 DEBUG: ScanForExternalSubtitles: Searching for subtitles...
18:25:26 T:139716007548672 DEBUG: OpenDir - Using authentication url smb://192.168.1.40/media/etc
18:25:26 T:139716007548672 DEBUG: ScanForExternalSubtitles: END (total time: 20 ms)
18:25:26 T:139716007548672 NOTICE: Creating Demuxer
18:25:26 T:139716007548672 DEBUG: Open - probing detected format [matroska,webm]
18:25:26 T:139716007548672 DEBUG: Open - avformat_find_stream_info starting
18:25:26 T:139716007548672 DEBUG: Open - av_find_stream_info finished
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: Input #0, matroska,webm, from 'smb://192.168.1.40/media/etc/Joe_sample.mkv':
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: Metadata:
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: encoder : libebml v1.3.0 + libmatroska v1.4.1
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: creation_time : 2014-07-08 22:15:43
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: Duration: 00:00:29.75, start: 0.000000, bitrate: 27288 kb/s
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: Stream #0:0: Video: h264 (High), yuv420p(tv, bt709), 1920x1080 [SAR 1:1 DAR 16:9], 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc (default)
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: Metadata:
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: BPS : 23874972
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: BPS-eng : 23874972
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: DURATION : 00:00:29.738000000
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: DURATION-eng : 00:00:29.738000000
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: NUMBER_OF_FRAMES: 713
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: NUMBER_OF_FRAMES-eng: 713
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: NUMBER_OF_BYTES : 88749243
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: NUMBER_OF_BYTES-eng: 88749243
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: _STATISTICS_WRITING_APP: mkvmerge v7.0.0 ('Where We Going') 64bit built on Jun 9 2014 15:16:27
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: _STATISTICS_WRITING_APP-eng: mkvmerge v7.0.0 ('Where We Going') 64bit built on Jun 9 2014 15:16:27
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: _STATISTICS_WRITING_DATE_UTC: 2014-07-08 22:15:43
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: _STATISTICS_WRITING_DATE_UTC-eng: 2014-07-08 22:15:43
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: _STATISTICS_TAGS: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: Stream #0:1(eng): Audio: dts (DTS-HD MA), 48000 Hz, 5.1(side), fltp, 1536 kb/s (default)
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: Metadata:
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: BPS : 3414756
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: BPS-eng : 3414756
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: DURATION : 00:00:29.740000000
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: DURATION-eng : 00:00:29.740000000
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: NUMBER_OF_FRAMES: 2787
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: NUMBER_OF_FRAMES-eng: 2787
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: NUMBER_OF_BYTES : 12694356
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: NUMBER_OF_BYTES-eng: 12694356
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: _STATISTICS_WRITING_APP: mkvmerge v7.0.0 ('Where We Going') 64bit built on Jun 9 2014 15:16:27
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: _STATISTICS_WRITING_APP-eng: mkvmerge v7.0.0 ('Where We Going') 64bit built on Jun 9 2014 15:16:27
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: _STATISTICS_WRITING_DATE_UTC: 2014-07-08 22:15:43
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: _STATISTICS_WRITING_DATE_UTC-eng: 2014-07-08 22:15:43
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: _STATISTICS_TAGS: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES
18:25:26 T:139716007548672 INFO: ffmpeg[2AFFD700]: _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES
18:25:26 T:139716007548672 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
18:25:26 T:139716007548672 DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1
18:25:26 T:139716007548672 NOTICE: Opening video stream: 0 source: 256
18:25:26 T:139716007548672 NOTICE: Creating video codec with codec id: 28
18:25:26 T:139716007548672 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:yes AMCodec:no MediaCodec:no OpenMax:no libstagefright:no VDPAU:yes VAAPI:yes
18:25:26 T:139716007548672 DEBUG: FactoryCodec - Video: - Opening
18:25:26 T:139716007548672 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
18:25:26 T:139716007548672 DEBUG: FactoryCodec - Video: ff-h264 - Opened
18:25:26 T:139716007548672 NOTICE: Creating video thread
18:25:26 T:139715979736832 NOTICE: Thread DVDPlayerVideo start, auto delete: false
18:25:26 T:139715979736832 NOTICE: running thread: video_thread
18:25:26 T:139716007548672 NOTICE: Opening audio stream: 1 source: 256
18:25:26 T:139716007548672 NOTICE: Finding audio codec for: 86020
18:25:26 T:139716007548672 DEBUG: FactoryCodec - Audio: passthrough - Opening
18:25:26 T:139715979736832 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
18:25:26 T:139716007548672 DEBUG: FactoryCodec - Audio: passthrough - Failed
18:25:26 T:139716007548672 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
18:25:26 T:139716007548672 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
18:25:26 T:139716007548672 NOTICE: Creating audio thread
18:25:26 T:139715971344128 NOTICE: Thread DVDPlayerAudio start, auto delete: false
18:25:26 T:139715971344128 NOTICE: running thread: CDVDPlayerAudio::Process()
18:25:26 T:139716007548672 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://192.168.1.40/media/etc/Joe_sample.mkv
18:25:26 T:139716007548672 DEBUG: OnPlayBackStarted : play state was 1, starting 1
18:25:26 T:139716007548672 DEBUG: CDVDPlayer::SetCaching - caching state 3
18:25:26 T:139717195147072 DEBUG: PlayFile : OpenFile succeed, play state 2
18:25:26 T:139717195147072 DEBUG: OnPlayBackStarted : play state was 2, starting 0
18:25:26 T:139715979736832 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
18:25:26 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:25:26 T:139715979736832 DEBUG: VAAPI - attempting to open codec 28 with profile 100 at level 41 with 4 reference frames
18:25:26 T:139715971344128 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(5000.000000, 1)
18:25:26 T:139715971344128 NOTICE: Creating audio stream (codec id: 86020, channels: 6, sample rate: 48000, no pass-through)
18:25:26 T:139715971344128 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
18:25:26 T:139717182379776 INFO: CActiveAESink::OpenSink - initialize sink
18:25:26 T:139715979736832 DEBUG: VAAPI - initialize version 0.35
18:25:26 T:139715979736832 DEBUG: VAAPI - vendor: Intel i965 driver for Intel(R) Haswell Mobile - 1.3.2
18:25:26 T:139715979736832 DEBUG: VAAPI - attrib 0 (get/set) min -100 max 100 value 0x0
18:25:26 T:139715979736832 DEBUG: VAAPI - attrib 1 (get/set) min 0 max 100 value 0x32
18:25:26 T:139715979736832 DEBUG: VAAPI - attrib 2 (get/set) min -180 max 180 value 0x0
18:25:26 T:139715979736832 DEBUG: VAAPI - attrib 3 (get/set) min 0 max 100 value 0x32
18:25:26 T:139715979736832 DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 0
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 1
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 13
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 6
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 7
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 8
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 9
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 10
18:25:26 T:139715979736832 DEBUG: VAAPI - profile -1
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 12
18:25:26 T:139715979736832 DEBUG: VAAPI - making sure 10 surfaces are allocated for given 4 references
18:25:26 T:139715979736832 DEBUG: VAAPI - attempting to open codec 28 with profile 100 at level 41 with 4 reference frames
18:25:26 T:139715979736832 DEBUG: VAAPI - attrib 0 (get/set) min -100 max 100 value 0x0
18:25:26 T:139715979736832 DEBUG: VAAPI - attrib 1 (get/set) min 0 max 100 value 0x32
18:25:26 T:139715979736832 DEBUG: VAAPI - attrib 2 (get/set) min -180 max 180 value 0x0
18:25:26 T:139715979736832 DEBUG: VAAPI - attrib 3 (get/set) min 0 max 100 value 0x32
18:25:26 T:139715979736832 DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 0
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 1
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 13
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 6
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 7
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 8
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 9
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 10
18:25:26 T:139715979736832 DEBUG: VAAPI - profile -1
18:25:26 T:139715979736832 DEBUG: VAAPI - profile 12
18:25:26 T:139715979736832 DEBUG: VAAPI - making sure 10 surfaces are allocated for given 4 references
18:25:26 T:139715979736832 DEBUG: VAAPI - destroying surface 0x4000000
18:25:26 T:139715979736832 DEBUG: VAAPI - destroying surface 0x4000001
18:25:26 T:139715979736832 DEBUG: VAAPI - destroying surface 0x4000002
18:25:26 T:139715979736832 DEBUG: VAAPI - destroying surface 0x4000003
18:25:26 T:139715979736832 DEBUG: VAAPI - destroying surface 0x4000004
18:25:26 T:139715979736832 DEBUG: VAAPI - destroying surface 0x4000005
18:25:26 T:139715979736832 DEBUG: VAAPI - destroying surface 0x4000006
18:25:26 T:139715979736832 DEBUG: VAAPI - destroying surface 0x4000007
18:25:26 T:139715979736832 DEBUG: VAAPI - destroying surface 0x4000008
18:25:26 T:139715979736832 DEBUG: VAAPI - destroying surface 0x4000009
18:25:26 T:139715979736832 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(125000.000000, 0)
18:25:26 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:25:26 T:139715979736832 NOTICE: fps: 23.976024, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080
18:25:26 T:139715979736832 DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 23.98. format: VAAPI
18:25:26 T:139715979736832 NOTICE: Display resolution DESKTOP : 1920x1200@ 59.95 - Full Screen (16)
18:25:26 T:139715979736832 DEBUG: CXBMCRenderManager::Configure - 5
18:25:26 T:139717195147072 NOTICE: Using GL_TEXTURE_2D
18:25:26 T:139717195147072 NOTICE: GL: Using VAAPI render method
18:25:26 T:139717195147072 NOTICE: GL: NPOT texture support detected
18:25:26 T:139717195147072 NOTICE: GL: Using GL_ARB_pixel_buffer_object
18:25:26 T:139717195147072 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://192.168.1.40/media/etc/Joe_sample.mkv)
18:25:26 T:139716007548672 DEBUG: CDVDPlayer::HandleMessages - player started 2
18:25:26 T:139717195147072 DEBUG: GetMovieId (smb://192.168.1.40/media/etc/Joe_sample.mkv), query = select idMovie from movie where idFile=172
18:25:26 T:139717195147072 DEBUG: GetEpisodeId (smb://192.168.1.40/media/etc/Joe_sample.mkv), query = select idEpisode from episode where idFile=172
18:25:26 T:139717195147072 DEBUG: GetMusicVideoId (smb://192.168.1.40/media/etc/Joe_sample.mkv), query = select idMVideo from musicvideo where idFile=172
18:25:26 T:139717195147072 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
18:25:26 T:139717195147072 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
18:25:26 T:139717195147072 DEBUG: UPnP: Building didl for object 'smb://192.168.1.40/media/etc/Joe_sample.mkv'
18:25:26 T:139717195147072 DEBUG: Activating window ID: 12005
18:25:26 T:139717195147072 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
18:25:26 T:139717195147072 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
18:25:26 T:139717195147072 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
18:25:26 T:139717195147072 INFO: GL: Enabling VSYNC
18:25:26 T:139717195147072 INFO: GL: Selected vsync mode 2
18:25:26 T:139717195147072 DEBUG: CLinuxRendererGL::UploadVAAPITexture - creating vaapi surface for texture 1
18:25:26 T:139717195147072 DEBUG: GL: Vertex Shader compilation log:
18:25:26 T:139717195147072 DEBUG: GL: Vertex Shader compiled successfully
18:25:26 T:139717195147072 DEBUG: GL: Pixel Shader compilation log:
18:25:26 T:139717195147072 DEBUG: GL: Fragment Shader compiled successfully
18:25:26 T:139717182379776 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=HDMI,DEV=0
18:25:26 T:139717182379776 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
18:25:26 T:139717182379776 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
18:25:26 T:139717182379776 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR
18:25:26 T:139717182379776 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=HDMI,DEV=0"
18:25:26 T:139717182379776 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=HDMI,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02"
18:25:26 T:139717182379776 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
18:25:26 T:139717182379776 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
18:25:26 T:139717182379776 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2048, bufferSize 8192
18:25:26 T:139715971344128 WARNING: CActiveAEStream::AddData - timeout waiting for buffer, paused: 0, cache time: 0.000000, free buffers: 0
18:25:26 T:139717182379776 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192
18:25:26 T:139717182379776 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 171 ms
18:25:26 T:139717182379776 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
18:25:26 T:139717182379776 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
18:25:26 T:139717182379776 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR
18:25:26 T:139717182379776 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
18:25:26 T:139717182379776 DEBUG: Output Device : HDA Intel
18:25:26 T:139717182379776 DEBUG: Sample Rate : 48000
18:25:26 T:139717182379776 DEBUG: Sample Format : AE_FMT_S32NE
18:25:26 T:139717182379776 DEBUG: Channel Count : 2
18:25:26 T:139717182379776 DEBUG: Channel Layout: FL,FR
18:25:26 T:139717182379776 DEBUG: Frames : 2048
18:25:26 T:139717182379776 DEBUG: Frame Samples : 4096
18:25:26 T:139717182379776 DEBUG: Frame Size : 8
18:25:26 T:139717190772480 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
18:25:26 T:139716007548672 DEBUG: Previous line repeats 1 times.
18:25:26 T:139716007548672 DEBUG: CDVDPlayer::HandleMessages - player started 1
18:25:26 T:139716007548672 DEBUG: CDVDPlayer::SetCaching - caching state 0
18:25:26 T:139715971344128 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:5199.383000, should be:9101.094114, error:3901.711114
18:25:26 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:25:26 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:25:26 T:139717195147072 DEBUG: CLinuxRendererGL::UploadVAAPITexture - creating vaapi surface for texture 2
18:25:26 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:25:26 T:139717195147072 DEBUG: CLinuxRendererGL::UploadVAAPITexture - creating vaapi surface for texture 3
18:25:26 T:139717195147072 DEBUG: CLinuxRendererGL::UploadVAAPITexture - creating vaapi surface for texture 4
18:25:26 T:139717195147072 DEBUG: CLinuxRendererGL::UploadVAAPITexture - creating vaapi surface for texture 0
18:25:26 T:139715971344128 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:132864.326114, should be:15666.666667, error:-117197.659448
18:25:27 T:139717195147072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0
18:25:27 T:139717195147072 DEBUG: OnKey: return (0xf00d) pressed, action is OSD
18:25:27 T:139717195147072 DEBUG: ------ Window Init (VideoOSD.xml) ------
18:25:27 T:139717195147072 INFO: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY
18:25:28 T:139717195147072 DEBUG: Keyboard: scancode: 0x20, sym: 0x006f, unicode: 0x006f, modifier: 0x0
18:25:28 T:139717195147072 DEBUG: OnKey: o (0xf04f) pressed, action is CodecInfo
18:25:28 T:139715971344128 DEBUG: CDVDPlayerAudio:: Discontinuity2 - was:2021089.947667, should be:1936525.594136, error:-84564.353530
18:25:30 T:139715971344128 DEBUG: CDVDPlayerAudio:: Discontinuity2 - was:3941893.916136, should be:3930080.965748, error:-11812.950389
18:25:31 T:139715979736832 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.33, frameduration: 41708.333333
18:25:40 T:139715979736832 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
18:25:42 T:139715971344128 DEBUG: CDVDPlayerAudio:: Discontinuity2 - was:15961982.309748, should be:15972829.854796, error:10847.545049
18:25:45 T:139715979736832 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:25:45 T:139715979736832 DEBUG: Previous line repeats 2 times.
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -5.493251, Bufferlevel: 1, Gain: 0.084292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:25:45 T:139715979736832 DEBUG: Previous line repeats 1 times.
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -5.493251, Bufferlevel: 2, Gain: 0.083292
18:25:45 T:139715979736832 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -5.057517, Bufferlevel: 0, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:25:45 T:139715979736832 DEBUG: Previous line repeats 1 times.
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -5.057517, Bufferlevel: 1, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:25:45 T:139715979736832 DEBUG: Previous line repeats 1 times.
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -5.057517, Bufferlevel: 2, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -5.057517, Bufferlevel: 3, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -4.572924, Bufferlevel: 1, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:25:45 T:139715979736832 DEBUG: Previous line repeats 1 times.
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -4.572924, Bufferlevel: 2, Gain: 0.084292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -4.572924, Bufferlevel: 3, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:25:45 T:139715979736832 DEBUG: Previous line repeats 2 times.
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -4.122923, Bufferlevel: 2, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -4.122923, Bufferlevel: 3, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -3.845032, Bufferlevel: 1, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:25:45 T:139715979736832 DEBUG: Previous line repeats 1 times.
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -3.845032, Bufferlevel: 2, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -3.845032, Bufferlevel: 3, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -3.845032, Bufferlevel: 4, Gain: 0.084292
18:25:45 T:139715979736832 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -3.350152, Bufferlevel: 0, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -3.350152, Bufferlevel: 3, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -3.350152, Bufferlevel: 4, Gain: 0.083292
18:25:45 T:139715979736832 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -3.350152, Bufferlevel: 4, Gain: 0.083292
18:25:45 T:139715979736832 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -3.350152, Bufferlevel: 4, Gain: 0.042292
18:25:45 T:139715979736832 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
18:25:45 T:139715979736832 WARNING: Previous line repeats 4 times.
18:25:45 T:139715979736832 ERROR: VAAPI - renderer still using all freed up surfaces by decoder
18:25:45 T:139715979736832 ERROR: VAAPI - unable to find free surface, trying to allocate a new one
18:25:45 T:139715979736832 DEBUG: VAAPI - making sure 11 surfaces are allocated for given 4 references
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -2.869458, Bufferlevel: 3, Gain: 0.083292
18:25:45 T:139715979736832 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
18:25:45 T:139715979736832 WARNING: Previous line repeats 1 times.
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -2.869458, Bufferlevel: 4, Gain: 0.083292
18:25:45 T:139715979736832 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
18:25:45 T:139715979736832 WARNING: Previous line repeats 2 times.
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -2.175155, Bufferlevel: 3, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -2.175155, Bufferlevel: 4, Gain: 0.083292
18:25:45 T:139715979736832 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -2.175155, Bufferlevel: 4, Gain: 0.084292
18:25:45 T:139715979736832 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
18:25:45 T:139715979736832 WARNING: Previous line repeats 5 times.
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -1.529546, Bufferlevel: 3, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -1.529546, Bufferlevel: 4, Gain: 0.083292
18:25:45 T:139715979736832 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -1.529546, Bufferlevel: 4, Gain: 0.083292
18:25:45 T:139715979736832 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -1.529546, Bufferlevel: 4, Gain: 0.042292
18:25:45 T:139715979736832 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:25:45 T:139715979736832 DEBUG: Previous line repeats 1 times.
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -0.791629, Bufferlevel: 2, Gain: 0.042292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -0.791629, Bufferlevel: 3, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
18:25:45 T:139715979736832 DEBUG: Previous line repeats 2 times.
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -0.223242, Bufferlevel: 2, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -0.223242, Bufferlevel: 3, Gain: 0.083292
18:25:45 T:139715979736832 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -0.223242, Bufferlevel: 4, Gain: 0.083292
18:25:50 T:139715979736832 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.33, frameduration: 41708.333333
18:25:55 T:139717042325248 DEBUG: Thread JobWorker 139717042325248 terminating (autodelete)
18:25:55 T:139716745750272 DEBUG: Thread JobWorker 139716745750272 terminating (autodelete)
18:25:55 T:139716460525312 DEBUG: Thread JobWorker 139716460525312 terminating (autodelete)
18:25:55 T:139715971344128 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_EOF
18:25:55 T:139715971344128 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
18:25:56 T:139715979736832 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
18:25:56 T:139716007548672 NOTICE: CDVDPlayer::OnExit()
18:25:56 T:139716007548672 NOTICE: DVDPlayer: eof, waiting for queues to empty
18:25:56 T:139716007548672 NOTICE: DVDPlayer: closing audio stream
18:25:56 T:139716007548672 NOTICE: Closing audio stream
18:25:56 T:139716007548672 NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty
18:25:56 T:139715971344128 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
18:25:56 T:139716007548672 NOTICE: Waiting for audio thread to exit
18:25:56 T:139715971344128 DEBUG: CDVDPlayerAudio::Process - Abort received, exiting thread
18:25:56 T:139715971344128 NOTICE: thread end: CDVDPlayerAudio::OnExit()
18:25:56 T:139715971344128 DEBUG: Thread DVDPlayerAudio 139715971344128 terminating
18:25:56 T:139716007548672 NOTICE: Closing audio device
18:25:56 T:139716007548672 NOTICE: Deleting audio codec
18:25:56 T:139716007548672 NOTICE: DVDPlayer: closing video stream
18:25:56 T:139716007548672 NOTICE: Closing video stream
18:25:56 T:139716007548672 NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty
18:25:56 T:139717190772480 DEBUG: CActiveAE::DiscardStream - audio stream deleted
18:25:56 T:139717190772480 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
18:25:56 T:139716007548672 NOTICE: waiting for video thread to exit
18:25:56 T:139715979736832 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
18:25:56 T:139715979736832 NOTICE: thread end: video_thread
18:25:56 T:139715979736832 DEBUG: Thread DVDPlayerVideo 139715979736832 terminating
18:25:56 T:139717190772480 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
18:25:56 T:139716007548672 NOTICE: deleting video codec
18:25:56 T:139716007548672 DEBUG: VAAPI - destroying surface 0x400000d
18:25:56 T:139716007548672 DEBUG: VAAPI - destroying surface 0x4000011
18:25:56 T:139716007548672 DEBUG: VAAPI - destroying surface 0x400000e
18:25:56 T:139716007548672 DEBUG: VAAPI - destroying surface 0x4000013
18:25:56 T:139716007548672 DEBUG: VAAPI - destroying surface 0x4000010
18:25:56 T:139716007548672 DEBUG: VAAPI - destroying surface 0x4000012
18:25:56 T:139716007548672 NOTICE: CDVDPlayer::OnExit() deleting demuxer
18:25:56 T:139716007548672 NOTICE: CDVDPlayer::OnExit() deleting input stream
18:25:56 T:139716007548672 DEBUG: CSmbFile::Close closing fd 10000
18:25:56 T:139716007548672 DEBUG: OnPlayBackEnded : play state was 2, starting 0
18:25:56 T:139716007548672 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
18:25:56 T:139716007548672 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
18:25:56 T:139716007548672 DEBUG: Thread DVDPlayer 139716007548672 terminating
18:25:56 T:139717195147072 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
18:25:56 T:139717195147072 DEBUG: ------ Window Deinit (VideoOSD.xml) ------
18:25:56 T:139717195147072 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
18:24:35 T:140067473893184 NOTICE: closing down remote control service
18:24:35 T:140067473893184 NOTICE: unload sections
18:24:35 T:140067473893184 NOTICE: application stopped...