16:03:39 T:140520737499072 NOTICE: special://profile/ is mapped to: special://masterprofile/ 16:03:39 T:140520737499072 NOTICE: ----------------------------------------------------------------------- 16:03:39 T:140520737499072 NOTICE: Starting Kodi (15.2 Git:02e7013). Platform: Linux x86 64-bit 16:03:39 T:140520737499072 NOTICE: Using Release Kodi x64 build 16:03:39 T:140520737499072 NOTICE: Kodi compiled Oct 19 2015 by GCC 4.8.4 for Linux x86 64-bit version 3.13.11 (199947) 16:03:39 T:140520737499072 NOTICE: Running on Ubuntu 14.04.3 LTS, kernel: Linux x86 64-bit version 3.13.0-71-generic 16:03:39 T:140520737499072 NOTICE: FFmpeg statically linked, version: 2.6.4-kodi-2.6.4-Isengard 16:03:39 T:140520737499072 NOTICE: Host CPU: AMD A8-5600K APU with Radeon(tm) HD Graphics, 4 cores available 16:03:39 T:140520737499072 NOTICE: special://xbmc/ is mapped to: /usr/share/kodi 16:03:39 T:140520737499072 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/kodi 16:03:39 T:140520737499072 NOTICE: special://masterprofile/ is mapped to: /home/ruschi/.kodi/userdata 16:03:39 T:140520737499072 NOTICE: special://home/ is mapped to: /home/ruschi/.kodi 16:03:39 T:140520737499072 NOTICE: special://temp/ is mapped to: /home/ruschi/.kodi/temp 16:03:39 T:140520737499072 NOTICE: The executable running is: /usr/lib/kodi/kodi.bin 16:03:39 T:140520737499072 NOTICE: Local hostname: htpc 16:03:39 T:140520737499072 NOTICE: Log File is located: /home/ruschi/.kodi/temp/kodi.log 16:03:39 T:140520737499072 NOTICE: ----------------------------------------------------------------------- 16:03:39 T:140520737499072 NOTICE: load settings... 16:03:39 T:140520737499072 ERROR: PulseAudio: Failed to connect context 16:03:39 T:140520737499072 NOTICE: PulseAudio might not be running. Context was not created. 16:03:39 T:140520234481408 NOTICE: Thread FDEventMonitor start, auto delete: false 16:03:39 T:140520737499072 NOTICE: Found 1 Lists of Devices 16:03:39 T:140520737499072 NOTICE: Enumerated ALSA devices: 16:03:39 T:140520737499072 NOTICE: Device 1 16:03:39 T:140520737499072 NOTICE: m_deviceName : default 16:03:39 T:140520737499072 NOTICE: m_displayName : Default (HDA NVidia HDMI 1) 16:03:39 T:140520737499072 NOTICE: m_displayNameExtra: 16:03:39 T:140520737499072 NOTICE: m_deviceType : AE_DEVTYPE_PCM 16:03:39 T:140520737499072 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR 16:03:39 T:140520737499072 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 16:03:39 T:140520737499072 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE 16:03:39 T:140520737499072 NOTICE: Device 2 16:03:39 T:140520737499072 NOTICE: m_deviceName : hdmi:CARD=NVidia,DEV=0 16:03:39 T:140520737499072 NOTICE: m_displayName : HDA NVidia 16:03:39 T:140520737499072 NOTICE: m_displayNameExtra: HDMI 16:03:39 T:140520737499072 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 16:03:39 T:140520737499072 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR 16:03:39 T:140520737499072 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 16:03:39 T:140520737499072 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_LPCM,AE_FMT_DTSHD,AE_FMT_TRUEHD,AE_FMT_EAC3,AE_FMT_DTS,AE_FMT_AC3,AE_FMT_AAC 16:03:39 T:140520737499072 NOTICE: Device 3 16:03:39 T:140520737499072 NOTICE: m_deviceName : hdmi:CARD=NVidia,DEV=1 16:03:39 T:140520737499072 NOTICE: m_displayName : HDA NVidia 16:03:39 T:140520737499072 NOTICE: m_displayNameExtra: ONK TX-SR508 on HDMI 16:03:39 T:140520737499072 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 16:03:39 T:140520737499072 NOTICE: m_channels : FL,FR,LFE,FC,BL,BR,SL,SR 16:03:39 T:140520737499072 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 16:03:39 T:140520737499072 NOTICE: m_dataFormats : AE_FMT_LPCM,AE_FMT_AC3,AE_FMT_DTS,AE_FMT_EAC3,AE_FMT_DTSHD,AE_FMT_TRUEHD,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_AAC 16:03:39 T:140520737499072 NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml) 16:03:39 T:140520737499072 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml) 16:03:39 T:140520737499072 NOTICE: Default DVD Player: dvdplayer 16:03:39 T:140520737499072 NOTICE: Default Video Player: dvdplayer 16:03:39 T:140520737499072 NOTICE: Default Audio Player: paplayer 16:03:39 T:140520737499072 NOTICE: Disabled debug logging due to GUI setting. Level 0. 16:03:39 T:140520737499072 NOTICE: Log level changed to "LOG_LEVEL_NORMAL" 16:03:39 T:140520737499072 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml 16:03:39 T:140520737499072 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 16:03:39 T:140520737499072 NOTICE: Loaded playercorefactory configuration 16:03:39 T:140520737499072 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml. 16:03:39 T:140520737499072 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping. 16:03:39 T:140520226088704 NOTICE: Thread ActiveAE start, auto delete: false 16:03:39 T:140520217696000 NOTICE: Thread AESink start, auto delete: false 16:03:39 T:140520737499072 NOTICE: Running database version Addons19 16:03:39 T:140520737499072 NOTICE: ADDONS: Using repository repository.xbmc.org 16:03:39 T:140520204826368 NOTICE: Thread RemoteControl start, auto delete: false 16:03:40 T:140520737499072 NOTICE: Setup SDL 16:03:40 T:140520737499072 NOTICE: Checking resolution 16 16:03:40 T:140520737499072 NOTICE: Using visual 0x28 16:03:40 T:140520737499072 NOTICE: GL_VENDOR = NVIDIA Corporation 16:03:40 T:140520737499072 NOTICE: GL_RENDERER = GeForce GT 630/PCIe/SSE2 16:03:40 T:140520737499072 NOTICE: GL_VERSION = 4.4.0 NVIDIA 340.96 16:03:40 T:140520737499072 NOTICE: GL_SHADING_LANGUAGE_VERSION = 4.40 NVIDIA via Cg compiler 16:03:40 T:140520737499072 NOTICE: GL_GPU_MEMORY_INFO_TOTAL_AVAILABLE_MEMORY_NVX = 4194304 16:03:40 T:140520737499072 NOTICE: GL_GPU_MEMORY_INFO_DEDICATED_VIDMEM_NVX = 4194304 16:03:40 T:140520737499072 NOTICE: GL_EXTENSIONS = GL_AMD_multi_draw_indirect GL_AMD_seamless_cubemap_per_texture GL_ARB_arrays_of_arrays GL_ARB_base_instance GL_ARB_bindless_texture GL_ARB_blend_func_extended GL_ARB_buffer_storage GL_ARB_clear_buffer_object GL_ARB_clear_texture GL_ARB_color_buffer_float GL_ARB_compatibility GL_ARB_compressed_texture_pixel_storage GL_ARB_conservative_depth GL_ARB_compute_shader GL_ARB_compute_variable_group_size GL_ARB_copy_buffer GL_ARB_copy_image GL_ARB_debug_output GL_ARB_depth_buffer_float GL_ARB_depth_clamp GL_ARB_depth_texture GL_ARB_draw_buffers GL_ARB_draw_buffers_blend GL_ARB_draw_indirect GL_ARB_draw_elements_base_vertex GL_ARB_draw_instanced GL_ARB_enhanced_layouts GL_ARB_ES2_compatibility GL_ARB_ES3_compatibility GL_ARB_explicit_attrib_location GL_ARB_explicit_uniform_location GL_ARB_fragment_coord_conventions GL_ARB_fragment_layer_viewport GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_framebuffer_no_attachments GL_ARB_framebuffer_object GL_ARB_framebuffer_sRGB GL_ARB_geometry_shader4 GL_ARB_get_program_binary GL_ARB_gpu_shader5 GL_ARB_gpu_shader_fp64 GL_ARB_half_float_pixel GL_ARB_half_float_vertex GL_ARB_imaging GL_ARB_indirect_parameters GL_ARB_instanced_arrays GL_ARB_internalformat_query GL_ARB_internalformat_query2 GL_ARB_invalidate_subdata GL_ARB_map_buffer_alignment GL_ARB_map_buffer_range GL_ARB_multi_bind GL_ARB_multi_draw_indirect GL_ARB_multisample GL_ARB_multitexture GL_ARB_occlusion_query GL_ARB_occlusion_query2 GL_ARB_pixel_buffer_object GL_ARB_point_parameters GL_ARB_point_sprite GL_ARB_program_interface_query GL_ARB_provoking_vertex GL_ARB_robust_buffer_access_behavior GL_ARB_robustness GL_ARB_sample_shading GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_seamless_cubemap_per_texture GL_ARB_separate_shader_objects GL_ARB_shader_atomic_counters GL_ARB_shader_bit_encoding GL_ARB_shader_draw_parameters GL_ARB_shader_group_vote GL_ARB_shader_image_load_store GL_ARB_shader_image_size GL_ARB_shader_objects GL_ARB_shader_precision GL_ARB_query_buffer_object GL_ARB_shader_storage_buffer_object GL_ARB_shader_subroutine GL_ARB_shader_texture_lod GL_ARB_shading_language_100 GL_ARB_shading_language_420pack GL_ARB_shading_language_include GL_ARB_shading_language_packing GL_ARB_shadow GL_ARB_sparse_texture GL_ARB_stencil_texturing GL_ARB_sync GL_ARB_tessellation_shader GL_ARB_texture_border_clamp GL_ARB_texture_buffer_object GL_ARB_texture_buffer_object_rgb32 GL_ARB_texture_buffer_range GL_ARB_texture_compression GL_ARB_texture_compression_bptc GL_ARB_texture_compression_rgtc GL_ARB_texture_cube_map GL_ARB_texture_cube_map_array GL_ARB_texture_env_add GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_float GL_ARB_texture_gather GL_ARB_texture_mirror_clamp_to_edge GL_ARB_texture_mirrored_repeat GL_ARB_texture_multisample GL_ARB_texture_non_power_of_two GL_ARB_texture_query_levels GL_ARB_texture_query_lod GL_ARB_texture_rectangle GL_ARB_texture_rg GL_ARB_texture_rgb10_a2ui GL_ARB_texture_stencil8 GL_ARB_texture_storage GL_ARB_texture_storage_multisample GL_ARB_texture_swizzle GL_ARB_texture_view GL_ARB_timer_query GL_ARB_transform_feedback2 GL_ARB_transform_feedback3 GL_ARB_transform_feedback_instanced GL_ARB_transpose_matrix GL_ARB_uniform_buffer_object GL_ARB_vertex_array_bgra GL_ARB_vertex_array_object GL_ARB_vertex_attrib_64bit GL_ARB_vertex_attrib_binding GL_ARB_vertex_buffer_object GL_ARB_vertex_program GL_ARB_vertex_shader GL_ARB_vertex_type_10f_11f_11f_rev GL_ARB_vertex_type_2_10_10_10_rev GL_ARB_viewport_array GL_ARB_window_pos GL_ATI_draw_buffers GL_ATI_texture_float GL_ATI_texture_mirror_once GL_S3_s3tc GL_EXT_texture_env_add GL_EXT_abgr GL_EXT_bgra GL_EXT_bindable_uniform GL_EXT_blend_color GL_EXT_blend_equation_separate GL_EXT_blend_func_separate GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_compiled_vertex_array GL_EXT_Cg_shader GL_EXT_depth_bounds_test GL_EXT_direct_state_access GL_EXT_draw_buffers2 GL_EXT_draw_instanced GL_EXT_draw_range_elements GL_EXT_fog_coord GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXTX_framebuffer_mixed_formats GL_EXT_framebuffer_multisample_blit_scaled GL_EXT_framebuffer_object GL_EXT_framebuffer_sRGB GL_EXT_geometry_shader4 GL_EXT_gpu_program_parameters GL_EXT_gpu_shader4 GL_EXT_multi_draw_arrays GL_EXT_packed_depth_stencil GL_EXT_packed_float GL_EXT_packed_pixels GL_EXT_pixel_buffer_object GL_EXT_point_parameters GL_EXT_provoking_vertex GL_EXT_rescale_normal GL_EXT_secondary_color GL_EXT_separate_shader_objects GL_EXT_separate_specular_color GL_EXT_shader_image_load_store GL_EXT_shader_integer_mix GL_EXT_shadow_funcs GL_EXT_stencil_two_side GL_EXT_stencil_wrap GL_EXT_texture3D GL_EXT_texture_array GL_EXT_texture_buffer_object GL_EXT_texture_compression_dxt1 GL_EXT_texture_compression_latc GL_EXT_texture_compression_rgtc GL_EXT_texture_compression_s3tc GL_EXT_texture_cube_map GL_EXT_texture_edge_clamp GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_EXT_texture_filter_anisotropic GL_EXT_texture_integer GL_EXT_texture_lod GL_EXT_texture_lod_bias GL_EXT_texture_mirror_clamp GL_EXT_texture_object GL_EXT_texture_shared_exponent GL_EXT_texture_sRGB GL_EXT_texture_sRGB_decode GL_EXT_texture_storage GL_EXT_texture_swizzle GL_EXT_timer_query GL_EXT_transform_feedback2 GL_EXT_vertex_array GL_EXT_vertex_array_bgra GL_EXT_vertex_attrib_64bit GL_EXT_x11_sync_object GL_EXT_import_sync_object GL_IBM_rasterpos_clip GL_IBM_texture_mirrored_repeat GL_KHR_debug GL_KTX_buffer_region GL_NV_bindless_multi_draw_indirect GL_NV_bindless_multi_draw_indirect_count GL_NV_bindless_texture GL_NV_blend_equation_advanced GL_NV_blend_square GL_NV_compute_program5 GL_NV_conditional_render GL_NV_copy_depth_to_color GL_NV_copy_image GL_NV_depth_buffer_float GL_NV_depth_clamp GL_NV_draw_texture GL_NV_ES1_1_compatibility GL_NV_ES3_1_compatibility GL_NV_explicit_multisample GL_NV_fence GL_NV_float_buffer GL_NV_fog_distance GL_NV_fragment_program GL_NV_fragment_program_option GL_NV_fragment_program2 GL_NV_framebuffer_multisample_coverage GL_NV_geometry_shader4 GL_NV_gpu_program4 GL_NV_gpu_program4_1 GL_NV_gpu_program5 GL_NV_gpu_program5_mem_extended GL_NV_gpu_program_fp64 GL_NV_gpu_shader5 GL_NV_half_float GL_NV_light_max_exponent GL_NV_multisample_coverage GL_NV_multisample_filter_hint GL_NV_occlusion_query GL_NV_packed_depth_stencil GL_NV_parameter_buffer_object GL_NV_parameter_buffer_object2 GL_NV_path_rendering GL_NV_pixel_data_range GL_NV_point_sprite GL_NV_primitive_restart GL_NV_register_combiners GL_NV_register_combiners2 GL_NV_shader_atomic_counters GL_NV_shader_atomic_float GL_NV_shader_atomic_int64 GL_NV_shader_buffer_load GL_NV_shader_storage_buffer_object GL_NV_texgen_reflection GL_NV_texture_barrier GL_NV_texture_compression_vtc GL_NV_texture_env_combine4 GL_NV_texture_expand_normal GL_NV_texture_multisample GL_NV_texture_rectangle GL_NV_texture_shader GL_NV_texture_shader2 GL_NV_texture_shader3 GL_NV_transform_feedback GL_NV_transform_feedback2 GL_NV_vdpau_interop GL_NV_vertex_array_range GL_NV_vertex_array_range2 GL_NV_vertex_attrib_integer_64bit GL_NV_vertex_buffer_unified_memory GL_NV_vertex_program GL_NV_vertex_program1_1 GL_NV_vertex_program2 GL_NV_vertex_program2_option GL_NV_vertex_program3 GL_NVX_conditional_render GL_NVX_gpu_memory_info GL_NVX_nvenc_interop GL_NV_shader_thread_group GL_NV_shader_thread_shuffle GL_KHR_blend_equation_advanced GL_SGIS_generate_mipmap GL_SGIS_texture_lod GL_SGIX_depth_texture GL_SGIX_shadow GL_SUN_slice_accum 16:03:40 T:140519968601856 NOTICE: Thread PeripBusCEC start, auto delete: false 16:03:40 T:140519960209152 NOTICE: Thread PeripBusUSBUdev start, auto delete: false 16:03:40 T:140520737499072 NOTICE: Running database version Addons19 16:03:40 T:140520737499072 NOTICE: Running database version ViewModes6 16:03:40 T:140520737499072 NOTICE: Running database version Textures13 16:03:40 T:140520737499072 NOTICE: Running database version MyMusic52 16:03:40 T:140520737499072 NOTICE: Running database version MyVideos93 16:03:40 T:140520737499072 NOTICE: Running database version TV29 16:03:40 T:140520737499072 NOTICE: Running database version Epg10 16:03:40 T:140520737499072 NOTICE: start dvd mediatype detection 16:03:40 T:140520190244608 NOTICE: Thread DetectDVDMedia start, auto delete: false 16:03:40 T:140520737499072 WARNING: JSONRPC: Could not parse type "PVR.Details.Channel" 16:03:40 T:140520737499072 WARNING: JSONRPC: Could not parse type "PVR.Details.ChannelGroup.Extended" 16:03:40 T:140520737499072 WARNING: JSONRPC: Could not parse type "GUI.Property.Value" 16:03:40 T:140520737499072 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList" 16:03:40 T:140519787198208 NOTICE: Thread LanguageInvoker start, auto delete: false 16:03:40 T:140519166433024 NOTICE: Previous line repeats 8 times. 16:03:40 T:140519166433024 NOTICE: Thread JobWorker start, auto delete: true 16:03:40 T:140519166433024 NOTICE: PVRManager - starting up 16:03:40 T:140519158040320 NOTICE: Thread PVRManager start, auto delete: false 16:03:40 T:140519149647616 NOTICE: Thread PVRClient start, auto delete: false 16:03:40 T:140519141254912 NOTICE: Thread LanguageInvoker start, auto delete: false 16:03:40 T:140520737499072 NOTICE: Previous line repeats 2 times. 16:03:40 T:140520737499072 NOTICE: initialize done 16:03:40 T:140520737499072 NOTICE: Running the application... 16:03:40 T:140519778805504 NOTICE: -->Python Interpreter Initialized<-- 16:03:41 T:140518621169408 NOTICE: Previous line repeats 10 times. 16:03:41 T:140518621169408 NOTICE: Thread LanguageInvoker start, auto delete: false 16:03:41 T:140518602241792 NOTICE: Thread JobWorker start, auto delete: true 16:03:41 T:140518593849088 NOTICE: Thread LanguageInvoker start, auto delete: false 16:03:41 T:140519183218432 NOTICE: Previous line repeats 1 times. 16:03:41 T:140519183218432 NOTICE: RCB Service: Start 16:03:41 T:140519183218432 NOTICE: RCB Service: launch RCB on startup = 16:03:41 T:140519183218432 NOTICE: RCB Service: scrape games on startup = false 16:03:41 T:140519183218432 NOTICE: RCB Service: Done 16:03:41 T:140520737499072 NOTICE: starting zeroconf publishing 16:03:41 T:140520737499072 NOTICE: Webserver: Starting... 16:03:41 T:140518235301632 NOTICE: Thread JobWorker start, auto delete: true 16:03:41 T:140520737499072 NOTICE: WebServer: Started the webserver 16:03:41 T:140520737499072 NOTICE: starting upnp client 16:03:41 T:140520737499072 NOTICE: ES: Starting event server 16:03:41 T:140517899757312 NOTICE: Thread EventServer start, auto delete: false 16:03:41 T:140517899757312 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777 16:03:41 T:140517899757312 NOTICE: UDP: Listening on port 9777 16:03:41 T:140517891364608 NOTICE: Thread TCPServer start, auto delete: false 16:03:41 T:140517874579200 NOTICE: Thread AirPlayServer start, auto delete: false 16:03:41 T:140519174825728 NOTICE: script.tv.show.next.aired: ### TV Show - Next Aired starting background proc (6.0.15) 16:03:41 T:140517857793792 NOTICE: Thread JobWorker start, auto delete: true 16:03:41 T:140517757146880 NOTICE: Thread PVRGUIInfo start, auto delete: false 16:03:41 T:140519141254912 NOTICE: RCB started 16:03:41 T:140519141254912 NOTICE: RCB: sys.argv = ['default.py', 'limit=12'] 16:03:41 T:140519141254912 NOTICE: RCB: param = default.py 16:03:41 T:140519141254912 NOTICE: RCB: setting launchRCB = True 16:03:41 T:140519141254912 NOTICE: RCB: param = limit=12 16:03:41 T:140519141254912 NOTICE: RCB: setting launchRCB = False 16:03:41 T:140519141254912 NOTICE: start gatherWidgetData 16:03:41 T:140519174825728 NOTICE: script.tv.show.next.aired: ### no prior data found 16:03:41 T:140519753627392 NOTICE: NEXTUP SERVICE NOTIFICATION Service -> Starting NextUp Service 16:03:41 T:140519753627392 NOTICE: NEXTUP SERVICE NOTIFICATION Service -> ======== START NEXTUP SERVICE NOTIFICATION ======== 16:03:41 T:140519787198208 NOTICE: -->Python Interpreter Initialized<-- 16:03:41 T:140519753627392 NOTICE: NEXTUP SERVICE NOTIFICATION Service -> KODI Version: 15.2 Git:02e7013 16:03:41 T:140519753627392 NOTICE: NEXTUP SERVICE NOTIFICATION Service -> NEXTUP SERVICE NOTIFICATION Version: 1.0.14 16:03:41 T:140519753627392 NOTICE: NEXTUP SERVICE NOTIFICATION Service -> Platform: Linux/RPi 16:03:41 T:140519132862208 NOTICE: version 3.1.2 started 16:03:41 T:140519141254912 NOTICE: RCB_INFO: Loading sqlite3 as DB engine 16:03:41 T:140519141254912 NOTICE: RCB: init log level 16:03:41 T:140519141254912 NOTICE: RCB: current log level: 2 16:03:41 T:140519141254912 NOTICE: RCB_INFO: Loading sqlite3 as DB engine 16:03:41 T:140518593849088 NOTICE: -->Python Interpreter Initialized<-- 16:03:41 T:140519141254912 NOTICE: /home/ruschi/.kodi/userdata/addon_data/script.games.rom.collection.browser/MyGames.db 16:03:41 T:140519141254912 NOTICE: RCB: No database available. Won't gather any data. 16:03:41 T:140519141254912 NOTICE: close Connection 16:03:41 T:140519141254912 NOTICE: RCB: launchRCB = False 16:03:41 T:140518621169408 NOTICE: -->Python Interpreter Initialized<-- 16:03:41 T:140518646347520 NOTICE: Previous line repeats 1 times. 16:03:41 T:140518646347520 NOTICE: StorageServer Module loaded RUN 16:03:41 T:140518646347520 NOTICE: StorageClient-2.5.4 Starting server 16:03:41 T:140517731497728 NOTICE: Thread LanguageInvoker start, auto delete: false 16:03:41 T:140519745234688 NOTICE: script.grab.fanart: Grab Fanart Service Started 16:03:41 T:140517731497728 NOTICE: -->Python Interpreter Initialized<-- 16:03:41 T:140517731497728 WARNING: CPythonInvoker(15): Script invoked without an addon. Adding all addon modules installed to python path as fallback. This behaviour will be removed in future version. 16:03:41 T:140518621169408 NOTICE: Thread EPGUpdater start, auto delete: false 16:03:41 T:140519158040320 NOTICE: Start - EPG thread started 16:03:41 T:140517731497728 NOTICE: metahandler: Initializing MetaHandlers version: 2.9.0 16:03:41 T:140517731497728 NOTICE: metahandler: Loading sqlite3 as DB engine version: 3.8.2 16:03:42 T:140520736687872 NOTICE: Thread RSSReader start, auto delete: false 16:03:43 T:140519174825728 NOTICE: script.tv.show.next.aired: ### starting data update 16:03:44 T:140519132862208 NOTICE: finished 16:03:44 T:140518235301632 ERROR: GetDirectory - Error getting 16:03:47 T:140520736687872 NOTICE: Thread RSSReader start, auto delete: false 16:04:00 T:140520737499072 NOTICE: Previous line repeats 1 times. 16:04:00 T:140520737499072 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM" 16:04:00 T:140520737499072 NOTICE: Enabled debug logging due to GUI setting. Level 2. 16:04:00 T:140520737499072 DEBUG: ------ Window Init () ------ 16:04:11 T:140520737499072 DEBUG: Keyboard: scancode: 0x09, sym: 0x001b, unicode: 0x001b, modifier: 0x0 16:04:11 T:140520737499072 DEBUG: OnKey: escape (0xf01b) pressed, action is PreviousMenu 16:04:11 T:140520737499072 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:04:12 T:140520737499072 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 16:04:12 T:140520737499072 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:04:12 T:140520737499072 DEBUG: ------ Window Init (Settings.xml) ------ 16:04:13 T:140520737499072 DEBUG: Keyboard: scancode: 0x09, sym: 0x001b, unicode: 0x001b, modifier: 0x0 16:04:13 T:140520737499072 DEBUG: OnKey: escape (0xf01b) pressed, action is PreviousMenu 16:04:13 T:140520737499072 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:04:13 T:140520737499072 DEBUG: ------ Window Deinit (Settings.xml) ------ 16:04:13 T:140520737499072 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:04:13 T:140520737499072 DEBUG: ------ Window Init (Home.xml) ------ 16:04:13 T:140519778805504 NOTICE: Thread LanguageInvoker start, auto delete: false 16:04:13 T:140519778805504 INFO: initializing python engine. 16:04:13 T:140519778805504 DEBUG: CPythonInvoker(16, /home/ruschi/.kodi/addons/script.favourites/default.py): start processing 16:04:13 T:140518602241792 ERROR: GetDirectory - Error getting 16:04:13 T:140519778805504 NOTICE: -->Python Interpreter Initialized<-- 16:04:13 T:140519778805504 DEBUG: CPythonInvoker(16, /home/ruschi/.kodi/addons/script.favourites/default.py): the source file to load is "/home/ruschi/.kodi/addons/script.favourites/default.py" 16:04:13 T:140519778805504 DEBUG: CPythonInvoker(16, /home/ruschi/.kodi/addons/script.favourites/default.py): setting the Python path to /home/ruschi/.kodi/addons/script.favourites:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0 16:04:13 T:140519778805504 DEBUG: CPythonInvoker(16, /home/ruschi/.kodi/addons/script.favourites/default.py): entering source directory /home/ruschi/.kodi/addons/script.favourites 16:04:13 T:140519778805504 DEBUG: CPythonInvoker(16, /home/ruschi/.kodi/addons/script.favourites/default.py): instantiating addon using automatically obtained id of "script.favourites" dependent on version 2.19.0 of the xbmc.python api 16:04:13 T:140519778805504 DEBUG: script.favourites: script version 6.0.3 started 16:04:13 T:140519778805504 DEBUG: script.favourites: ### params: {} 16:04:13 T:140519778805504 DEBUG: script.favourites: script stopped 16:04:13 T:140519778805504 INFO: CPythonInvoker(16, /home/ruschi/.kodi/addons/script.favourites/default.py): script successfully run 16:04:14 T:140519778805504 INFO: Python script stopped 16:04:14 T:140519778805504 DEBUG: Thread LanguageInvoker 140519778805504 terminating 16:04:14 T:140520737499072 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86_64-linux.so) 16:04:14 T:140520737499072 DEBUG: Unloading: /ImageLib-x86_64-linux.so 16:04:14 T:140520737499072 DEBUG: Keyboard: scancode: 0x71, sym: 0x0114, unicode: 0x0000, modifier: 0x0 16:04:14 T:140520737499072 DEBUG: OnKey: left (0xf082) pressed, action is Left 16:04:15 T:140520737499072 DEBUG: Keyboard: scancode: 0x71, sym: 0x0114, unicode: 0x0000, modifier: 0x0 16:04:15 T:140520737499072 DEBUG: OnKey: left (0xf082) pressed, action is Left 16:04:16 T:140520737499072 DEBUG: Keyboard: scancode: 0x71, sym: 0x0114, unicode: 0x0000, modifier: 0x0 16:04:16 T:140520737499072 DEBUG: OnKey: left (0xf082) pressed, action is Left 16:04:16 T:140518602241792 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-x86_64-linux.so) 16:04:16 T:140518602241792 DEBUG: Loading: /usr/lib/kodi/system/ImageLib-x86_64-linux.so 16:04:16 T:140520737499072 DEBUG: Keyboard: scancode: 0x71, sym: 0x0114, unicode: 0x0000, modifier: 0x0 16:04:16 T:140520737499072 DEBUG: OnKey: left (0xf082) pressed, action is Left 16:04:16 T:140519166433024 ERROR: GetDirectory - Error getting 16:04:17 T:140520737499072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:04:17 T:140520737499072 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:04:17 T:140520737499072 DEBUG: Activating window ID: 10025 16:04:17 T:140520737499072 DEBUG: ------ Window Deinit (Home.xml) ------ 16:04:17 T:140520737499072 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 16:04:17 T:140520737499072 INFO: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY 16:04:18 T:140520737499072 DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.youtube/) 16:04:18 T:140520737499072 DEBUG: ParentPath = [plugin://plugin.video.youtube/] 16:04:18 T:140520737499072 DEBUG: StartScript - calling plugin YouTube('plugin://plugin.video.youtube/','3','') 16:04:18 T:140520737499072 DEBUG: WaitOnScriptResult - waiting on the YouTube (id=17) plugin... 16:04:18 T:140519778805504 NOTICE: Thread LanguageInvoker start, auto delete: false 16:04:18 T:140519778805504 INFO: initializing python engine. 16:04:18 T:140519778805504 DEBUG: CPythonInvoker(17, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): start processing 16:04:18 T:140519778805504 NOTICE: -->Python Interpreter Initialized<-- 16:04:18 T:140519778805504 DEBUG: CPythonInvoker(17, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): the source file to load is "/home/ruschi/.kodi/addons/plugin.video.youtube/default.py" 16:04:18 T:140519778805504 DEBUG: CPythonInvoker(17, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): setting the Python path to /home/ruschi/.kodi/addons/plugin.video.youtube:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0 16:04:18 T:140519778805504 DEBUG: CPythonInvoker(17, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): entering source directory /home/ruschi/.kodi/addons/plugin.video.youtube 16:04:18 T:140519778805504 DEBUG: CPythonInvoker(17, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): instantiating addon using automatically obtained id of "plugin.video.youtube" dependent on version 2.1.0 of the xbmc.python api 16:04:18 T:140518235301632 ERROR: DoWork - Direct texture file loading failed for special://skin/backgrounds/default.jpg 16:04:18 T:140518235301632 DEBUG: GetImageHash - unable to stat url special://skin/backgrounds/default.jpg 16:04:18 T:140519778805504 DEBUG: [plugin.video.youtube] Starting Kodion framework by bromix... 16:04:18 T:140519778805504 NOTICE: [plugin.video.youtube] Running: YouTube (5.1.13) on Isengard (15.2) with Python 2.7.6 16:04:18 T:140519778805504 DEBUG: [plugin.video.youtube] Path: "/ 16:04:18 T:140519778805504 DEBUG: [plugin.video.youtube] Params: "{}" 16:04:18 T:140519778805504 DEBUG: [plugin.video.youtube] Selecting YouTube config "Isengard" 16:04:18 T:140519778805504 DEBUG: [plugin.video.youtube] User is not logged in 16:04:18 T:140519778805504 DEBUG: [plugin.video.youtube] Selecting YouTube config "Isengard" 16:04:18 T:140519778805504 DEBUG: [plugin.video.youtube] User is not logged in 16:04:18 T:140519778805504 DEBUG: POParser: loaded 100 strings from file /home/ruschi/.kodi/addons/plugin.video.youtube/resources/language/German/strings.po 16:04:18 T:140519778805504 DEBUG: POParser: loaded 0 strings from file /home/ruschi/.kodi/addons/plugin.video.youtube/resources/language/English/strings.po 16:04:18 T:140520737499072 DEBUG: WaitOnScriptResult- plugin returned successfully 16:04:18 T:140519778805504 DEBUG: [plugin.video.youtube] Override view mode to "50" 16:04:18 T:140519778805504 DEBUG: [plugin.video.youtube] Shutdown of Kodion 16:04:18 T:140519778805504 INFO: CPythonInvoker(17, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): script successfully run 16:04:18 T:140519132862208 NOTICE: Thread BackgroundLoader start, auto delete: false 16:04:18 T:140519132862208 DEBUG: Thread BackgroundLoader 140519132862208 terminating 16:04:18 T:140519132862208 NOTICE: Thread BackgroundLoader start, auto delete: false 16:04:18 T:140519132862208 DEBUG: Thread BackgroundLoader 140519132862208 terminating 16:04:18 T:140519778805504 INFO: Python script stopped 16:04:18 T:140519778805504 DEBUG: Thread LanguageInvoker 140519778805504 terminating 16:04:20 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:04:20 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:04:20 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:04:20 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:04:21 T:140520737499072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:04:21 T:140520737499072 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:04:21 T:140520737499072 DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.youtube/kodion/search/list/) 16:04:21 T:140520737499072 DEBUG: ParentPath = [plugin://plugin.video.youtube/] 16:04:21 T:140518602241792 DEBUG: StartScript - calling plugin YouTube('plugin://plugin.video.youtube/kodion/search/list/','4','') 16:04:21 T:140518602241792 DEBUG: WaitOnScriptResult - waiting on the YouTube (id=18) plugin... 16:04:21 T:140519778805504 NOTICE: Thread LanguageInvoker start, auto delete: false 16:04:21 T:140519778805504 INFO: initializing python engine. 16:04:21 T:140519778805504 DEBUG: CPythonInvoker(18, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): start processing 16:04:21 T:140519778805504 NOTICE: -->Python Interpreter Initialized<-- 16:04:21 T:140519778805504 DEBUG: CPythonInvoker(18, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): the source file to load is "/home/ruschi/.kodi/addons/plugin.video.youtube/default.py" 16:04:21 T:140519778805504 DEBUG: CPythonInvoker(18, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): setting the Python path to /home/ruschi/.kodi/addons/plugin.video.youtube:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0 16:04:21 T:140519778805504 DEBUG: CPythonInvoker(18, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): entering source directory /home/ruschi/.kodi/addons/plugin.video.youtube 16:04:21 T:140519778805504 DEBUG: CPythonInvoker(18, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): instantiating addon using automatically obtained id of "plugin.video.youtube" dependent on version 2.1.0 of the xbmc.python api 16:04:21 T:140519778805504 DEBUG: [plugin.video.youtube] Starting Kodion framework by bromix... 16:04:21 T:140519778805504 NOTICE: [plugin.video.youtube] Running: YouTube (5.1.13) on Isengard (15.2) with Python 2.7.6 16:04:21 T:140519778805504 DEBUG: [plugin.video.youtube] Path: "/kodion/search/list/ 16:04:21 T:140519778805504 DEBUG: [plugin.video.youtube] Params: "{}" 16:04:21 T:140519778805504 DEBUG: POParser: loaded 100 strings from file /home/ruschi/.kodi/addons/plugin.video.youtube/resources/language/German/strings.po 16:04:21 T:140519778805504 DEBUG: POParser: loaded 0 strings from file /home/ruschi/.kodi/addons/plugin.video.youtube/resources/language/English/strings.po 16:04:21 T:140518602241792 DEBUG: WaitOnScriptResult- plugin returned successfully 16:04:21 T:140519778805504 DEBUG: [plugin.video.youtube] Override view mode to "50" 16:04:21 T:140519778805504 DEBUG: [plugin.video.youtube] Shutdown of Kodion 16:04:21 T:140519778805504 INFO: CPythonInvoker(18, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): script successfully run 16:04:21 T:140519132862208 NOTICE: Thread BackgroundLoader start, auto delete: false 16:04:21 T:140519778805504 INFO: Python script stopped 16:04:21 T:140519778805504 DEBUG: Thread LanguageInvoker 140519778805504 terminating 16:04:21 T:140519132862208 DEBUG: Thread BackgroundLoader 140519132862208 terminating 16:04:21 T:140520737499072 INFO: CheckIdle - Closing session to http://feeds.xbmc.org (easy=0x7fccec024470, multi=0x7fccec0ba400) 16:04:22 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:04:22 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:04:22 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:04:22 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:04:24 T:140520737499072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:04:24 T:140520737499072 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:04:24 T:140520737499072 DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.youtube/kodion/search/query/?q=coldplay) 16:04:24 T:140520737499072 DEBUG: ParentPath = [plugin://plugin.video.youtube/kodion/search/list/] 16:04:24 T:140517857793792 DEBUG: StartScript - calling plugin YouTube('plugin://plugin.video.youtube/kodion/search/query/','5','?q=coldplay') 16:04:24 T:140517857793792 DEBUG: WaitOnScriptResult - waiting on the YouTube (id=19) plugin... 16:04:24 T:140519778805504 NOTICE: Thread LanguageInvoker start, auto delete: false 16:04:24 T:140519778805504 INFO: initializing python engine. 16:04:24 T:140519778805504 DEBUG: CPythonInvoker(19, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): start processing 16:04:24 T:140519778805504 NOTICE: -->Python Interpreter Initialized<-- 16:04:24 T:140519778805504 DEBUG: CPythonInvoker(19, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): the source file to load is "/home/ruschi/.kodi/addons/plugin.video.youtube/default.py" 16:04:24 T:140519778805504 DEBUG: CPythonInvoker(19, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): setting the Python path to /home/ruschi/.kodi/addons/plugin.video.youtube:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0 16:04:24 T:140519778805504 DEBUG: CPythonInvoker(19, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): entering source directory /home/ruschi/.kodi/addons/plugin.video.youtube 16:04:24 T:140519778805504 DEBUG: CPythonInvoker(19, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): instantiating addon using automatically obtained id of "plugin.video.youtube" dependent on version 2.1.0 of the xbmc.python api 16:04:24 T:140519778805504 DEBUG: [plugin.video.youtube] Starting Kodion framework by bromix... 16:04:24 T:140519778805504 NOTICE: [plugin.video.youtube] Running: YouTube (5.1.13) on Isengard (15.2) with Python 2.7.6 16:04:24 T:140519778805504 DEBUG: [plugin.video.youtube] Path: "/kodion/search/query/ 16:04:24 T:140519778805504 DEBUG: [plugin.video.youtube] Params: "{'q': u'coldplay'}" 16:04:24 T:140519778805504 DEBUG: [plugin.video.youtube] Setting content-type: "episodes" for "/kodion/search/query/" 16:04:24 T:140519778805504 DEBUG: POParser: loaded 100 strings from file /home/ruschi/.kodi/addons/plugin.video.youtube/resources/language/German/strings.po 16:04:24 T:140519778805504 DEBUG: POParser: loaded 0 strings from file /home/ruschi/.kodi/addons/plugin.video.youtube/resources/language/English/strings.po 16:04:24 T:140519778805504 DEBUG: [plugin.video.youtube] Selecting YouTube config "Isengard" 16:04:24 T:140519778805504 DEBUG: [plugin.video.youtube] User is not logged in 16:04:25 T:140520737499072 DEBUG: ------ Window Init (DialogBusy.xml) ------ 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] Selecting YouTube config "Isengard" 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] User is not logged in 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'DQhLos5d1sE' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'kcASPx3-HuI' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'BCwNmhHbMKg' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'QtXby3twMmI' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'lLSuP3d1Mrk' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'k23nXx6e-80' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'pY9b6jgbNyc' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'wOjn5TKo1tU' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'hqIVkWFoJTM' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'k4V3Mo61fJM' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'd020hcWA_Wg' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'Wpl_6jotaHA' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video '0k_1kvDh2UA' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video '1Uw6ZkbsAH8' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'BQeMxWjpr-Y' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'z1rYmzQ8C9Q' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'vMAyzVr_fZI' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'fIyFgygYW7s' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'RB-RcX5DS5A' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video '-1jd4o68_XY' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video '1PvBc2TOpE4' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'fXSovfzyx28' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'fyMhvkC3A84' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'P8jOQUsTU9o' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video '7atDQreame4' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'zs7xO5P3Az4' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video '1G4isv_Fylg' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'yKNxeF4KMsY' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'gKM15TaKLUI' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'zTFBJgnNgU4' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'CkiocVjRcDQ' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'QKcxhQZxvdw' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'zp7NtW_hKJI' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'yqKigvxv07c' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video '49tyKFO66VE' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video '1aFiQy4sYTA' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'VPRjCeoBqrI' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'WAGbq3A9HfA' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'X0DAzVQLxYM' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'EdBym7kv2IM' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'gnIZ7RMuLpU' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video '5y6M2ABEifc' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video '5KkWGy7W3_o' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'Qtb11P1FWnc' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'n1SLA7p5gnE' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'dvgZkm1xWPE' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'EH9meoWmAOM' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'MN-uFHB05DQ' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'vV6ppqac-gM' cached 16:04:25 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'QVJCFE3nauw' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UCp80KapOiR-qBQ5Sti4L0OQ' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UC-FQUIVQ-bZiefzBiQAa8Fw' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UClIqjWWDRuPzArrd8oWFGZw' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UCxpeJVKyjnR3YjFOHwfgv7A' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UCmEcJ34S67VFuawvOa5wlXw' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UCty-MGSNkEksyfJAjcFJOug' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UCz8CrS5y3MoH11ukNz0bgGg' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UC1SlayWbAaMp0DmLfU71SKQ' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UCe8K0HZ2tROUl5psHoCMsHg' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UC2kTZB_yeYgdAg4wP2tEryA' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UCqJTWy1wPr4gEiQc1Etigcw' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UC-qiUM_5wzBLq_muXHSmBQQ' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UCDPM_n1atn2ijUwHd0NNRQw' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UCFAJqfGFTK21xw8abWehKYQ' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UCp0hYYBW6IMayGgR-WeoCvQ' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UCCT0fg1-EJ4C7G764N7MFRA' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UCJ0uqCI0Vqr2Rrt1HseGirg' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UCCDccz7bJ9XdwTBkEX1qKlg' cached 16:04:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for channel 'UCOpCbNV4O5AR0hx1WkUYLvg' cached 16:04:27 T:140519778805504 DEBUG: POParser: loaded 100 strings from file /home/ruschi/.kodi/addons/plugin.video.youtube/resources/language/German/strings.po 16:04:27 T:140519778805504 DEBUG: POParser: loaded 0 strings from file /home/ruschi/.kodi/addons/plugin.video.youtube/resources/language/English/strings.po 16:04:27 T:140519778805504 DEBUG: [plugin.video.youtube] Converting VideoItem 16:04:27 T:140517857793792 DEBUG: Previous line repeats 49 times. 16:04:27 T:140517857793792 DEBUG: WaitOnScriptResult- plugin returned successfully 16:04:27 T:140519778805504 DEBUG: [plugin.video.youtube] Override view mode to "50" 16:04:27 T:140519778805504 DEBUG: [plugin.video.youtube] Shutdown of Kodion 16:04:27 T:140519778805504 INFO: CPythonInvoker(19, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): script successfully run 16:04:27 T:140520737499072 DEBUG: Saving fileitems [plugin://plugin.video.youtube/kodion/search/query/?q=coldplay] 16:04:27 T:140520737499072 DEBUG: -- items: 54, sort method: 0, ascending: false 16:04:27 T:140520737499072 DEBUG: RunQuery took 0 ms for 9 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=247 16:04:27 T:140517633332992 NOTICE: Thread BackgroundLoader start, auto delete: false 16:04:27 T:140517633332992 DEBUG: Thread BackgroundLoader 140517633332992 terminating 16:04:27 T:140519778805504 INFO: Python script stopped 16:04:27 T:140519778805504 DEBUG: Thread LanguageInvoker 140519778805504 terminating 16:04:27 T:140520737499072 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 16:04:28 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:04:28 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:04:28 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:04:28 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:04:28 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:04:28 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:04:29 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:04:29 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:04:31 T:140520737499072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:04:31 T:140520737499072 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:04:31 T:140520737499072 INFO: Loading skin file: DialogContextMenu.xml, load type: KEEP_IN_MEMORY 16:04:31 T:140520737499072 DEBUG: ------ Window Init (DialogContextMenu.xml) ------ 16:04:32 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:04:32 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:04:32 T:140520737499072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:04:32 T:140520737499072 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:04:33 T:140520737499072 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------ 16:04:33 T:140520737499072 DEBUG: OnPlayMedia plugin://plugin.video.youtube/play/?video_id=QtXby3twMmI 16:04:33 T:140520737499072 DEBUG: GetMovieId (plugin://plugin.video.youtube/play/?video_id=QtXby3twMmI), query = select idMovie from movie where idFile=247 16:04:33 T:140520737499072 DEBUG: GetEpisodeId (plugin://plugin.video.youtube/play/?video_id=QtXby3twMmI), query = select idEpisode from episode where idFile=247 16:04:33 T:140520737499072 DEBUG: GetMusicVideoId (plugin://plugin.video.youtube/play/?video_id=QtXby3twMmI), query = select idMVideo from musicvideo where idFile=247 16:04:33 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:04:33 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:04:33 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Playlist, sender=xbmc, message=OnAdd) 16:04:33 T:140520737499072 DEBUG: StartScript - calling plugin YouTube('plugin://plugin.video.youtube/play/','6','?video_id=QtXby3twMmI') 16:04:33 T:140520737499072 DEBUG: WaitOnScriptResult - waiting on the YouTube (id=20) plugin... 16:04:33 T:140519778805504 NOTICE: Thread LanguageInvoker start, auto delete: false 16:04:33 T:140519778805504 INFO: initializing python engine. 16:04:33 T:140519778805504 DEBUG: CPythonInvoker(20, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): start processing 16:04:33 T:140519778805504 NOTICE: -->Python Interpreter Initialized<-- 16:04:33 T:140519778805504 DEBUG: CPythonInvoker(20, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): the source file to load is "/home/ruschi/.kodi/addons/plugin.video.youtube/default.py" 16:04:33 T:140519778805504 DEBUG: CPythonInvoker(20, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): setting the Python path to /home/ruschi/.kodi/addons/plugin.video.youtube:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0 16:04:33 T:140519778805504 DEBUG: CPythonInvoker(20, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): entering source directory /home/ruschi/.kodi/addons/plugin.video.youtube 16:04:33 T:140519778805504 DEBUG: CPythonInvoker(20, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): instantiating addon using automatically obtained id of "plugin.video.youtube" dependent on version 2.1.0 of the xbmc.python api 16:04:33 T:140519778805504 DEBUG: [plugin.video.youtube] Starting Kodion framework by bromix... 16:04:33 T:140519778805504 NOTICE: [plugin.video.youtube] Running: YouTube (5.1.13) on Isengard (15.2) with Python 2.7.6 16:04:33 T:140519778805504 DEBUG: [plugin.video.youtube] Path: "/play/ 16:04:33 T:140519778805504 DEBUG: [plugin.video.youtube] Params: "{'video_id': u'QtXby3twMmI'}" 16:04:33 T:140519778805504 DEBUG: [plugin.video.youtube] Selecting YouTube config "Isengard" 16:04:33 T:140519778805504 DEBUG: [plugin.video.youtube] User is not logged in 16:04:34 T:140520737499072 DEBUG: DialogProgress::StartModal called 16:04:34 T:140520737499072 DEBUG: ------ Window Init (DialogProgress.xml) ------ 16:04:34 T:140520737499072 INFO: Loading skin file: DialogProgress.xml, load type: KEEP_IN_MEMORY 16:04:34 T:140519778805504 DEBUG: [plugin.video.youtube] selectable streams: 6 16:04:34 T:140519778805504 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [720, 0], 'video': {'encoding': 'h.264', 'height': 720}, 'title': '[B]720p[/B] (mp4;h.264 / aac@192)', 'url': 'https://r5---sn-5hne6n7k.googlevideo.com/videoplayback?ipbits=0&ratebypass=yes&initcwndbps=556250&expire=1450818273&upn=-3F-6-sjP2M&mime=video/mp4&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&fexp=9407051,9410705,9415327,9416126,9418400,9418750,9419452,9419549,9420452,9421249,9422596,9423293,9423662,9423853,9424302,9426282&sver=3&ms=au&mt=1450796472&mv=m&pl=24&key=yt6&mm=31&mn=sn-5hne6n7k&id=o-AKM5y7gcniGyb9CVFLJVmvg45M5RxZ715TF8iPLeYnU5&dur=315.628&source=youtube&ip=213.162.159.78&requiressl=yes&lmt=1448866065137762&itag=22&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&signature=B79A79252830FA9508F542F01BAA5095089DC120995.D308810D06122EE69C5C4F5EEED41CF706D89C7CC7C', 'container': 'mp4', 'audio': {'bitrate': 192, 'encoding': 'aac'}} 16:04:34 T:140519778805504 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [360, 0], 'video': {'encoding': 'h.264', 'height': 360}, 'title': '[B]360p[/B] (mp4;h.264 / aac@96)', 'url': 'https://r5---sn-5hne6n7k.googlevideo.com/videoplayback?ipbits=0&ratebypass=yes&initcwndbps=556250&expire=1450818273&upn=-3F-6-sjP2M&mime=video/mp4&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&fexp=9407051,9410705,9415327,9416126,9418400,9418750,9419452,9419549,9420452,9421249,9422596,9423293,9423662,9423853,9424302,9426282&sver=3&ms=au&mt=1450796472&mv=m&pl=24&key=yt6&mm=31&mn=sn-5hne6n7k&id=o-AKM5y7gcniGyb9CVFLJVmvg45M5RxZ715TF8iPLeYnU5&dur=315.628&source=youtube&ip=213.162.159.78&requiressl=yes&lmt=1448866025289520&itag=18&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&signature=F4A24A5DE2ECE292813CDDF9D1AA46FCBFC003CA6DA.3045C2105D648BAC04C41079AF64E6D0EF2930C00C0', 'container': 'mp4', 'audio': {'bitrate': 96, 'encoding': 'aac'}} 16:04:34 T:140519778805504 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [360, -1], 'video': {'encoding': 'vp8', 'height': 360}, 'title': '[B]360p[/B] (webm;vp8 / vorbis@128)', 'url': 'https://r5---sn-5hne6n7k.googlevideo.com/videoplayback?ipbits=0&ratebypass=yes&initcwndbps=556250&expire=1450818273&upn=-3F-6-sjP2M&mime=video/webm&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&fexp=9407051,9410705,9415327,9416126,9418400,9418750,9419452,9419549,9420452,9421249,9422596,9423293,9423662,9423853,9424302,9426282&sver=3&ms=au&mt=1450796472&mv=m&pl=24&key=yt6&mm=31&mn=sn-5hne6n7k&id=o-AKM5y7gcniGyb9CVFLJVmvg45M5RxZ715TF8iPLeYnU5&dur=0.000&source=youtube&ip=213.162.159.78&requiressl=yes&lmt=1448577957824899&itag=43&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&signature=3122122AF789D2462316985F6527264763346D84C12.035BADF68645991963A57D74FD0D67B28C423157157', 'container': 'webm', 'audio': {'bitrate': 128, 'encoding': 'vorbis'}} 16:04:34 T:140519778805504 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [240, 0], 'video': {'encoding': 'h.263', 'height': 240}, 'title': '[B]240p[/B] (flv;h.263 / mp3@64)', 'url': 'https://r5---sn-5hne6n7k.googlevideo.com/videoplayback?ipbits=0&initcwndbps=556250&expire=1450818273&upn=-3F-6-sjP2M&mime=video/x-flv&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&fexp=9407051,9410705,9415327,9416126,9418400,9418750,9419452,9419549,9420452,9421249,9422596,9423293,9423662,9423853,9424302,9426282&sver=3&ms=au&mt=1450796472&mv=m&pl=24&key=yt6&mm=31&mn=sn-5hne6n7k&id=o-AKM5y7gcniGyb9CVFLJVmvg45M5RxZ715TF8iPLeYnU5&dur=315.611&source=youtube&ip=213.162.159.78&requiressl=yes&lmt=1448576805809867&itag=5&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,requiressl,source,upn,expire&signature=9C1FC195274F0F5BF2DA0B5B957AE20EEED30F654BD.CF140E557428548C3E18778D4033D5FAD7A7E793793', 'container': 'flv', 'audio': {'bitrate': 64, 'encoding': 'mp3'}} 16:04:34 T:140519778805504 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [240, -20], 'video': {'encoding': 'mpeg-4', 'height': 240}, 'title': '[B]240p[/B] (3gp;mpeg-4 / aac@32)', 'url': 'https://r5---sn-5hne6n7k.googlevideo.com/videoplayback?ipbits=0&initcwndbps=556250&expire=1450818273&upn=-3F-6-sjP2M&mime=video/3gpp&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&fexp=9407051,9410705,9415327,9416126,9418400,9418750,9419452,9419549,9420452,9421249,9422596,9423293,9423662,9423853,9424302,9426282&sver=3&ms=au&mt=1450796472&mv=m&pl=24&key=yt6&mm=31&mn=sn-5hne6n7k&id=o-AKM5y7gcniGyb9CVFLJVmvg45M5RxZ715TF8iPLeYnU5&dur=315.652&source=youtube&ip=213.162.159.78&requiressl=yes&lmt=1448576752110001&itag=36&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,requiressl,source,upn,expire&signature=AC49C41A8AC8F910D94D553294CF8A0F6A1A97F7A4A.35642BB1C54D055E2BB710B2E745D410561D84A34A3', 'container': '3gp', 'audio': {'bitrate': 32, 'encoding': 'aac'}} 16:04:34 T:140519778805504 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [144, -20], 'video': {'encoding': 'mpeg-4', 'height': 144}, 'title': '[B]144p[/B] (3gp;mpeg-4 / aac@24)', 'url': 'https://r5---sn-5hne6n7k.googlevideo.com/videoplayback?ipbits=0&initcwndbps=556250&expire=1450818273&upn=-3F-6-sjP2M&mime=video/3gpp&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&fexp=9407051,9410705,9415327,9416126,9418400,9418750,9419452,9419549,9420452,9421249,9422596,9423293,9423662,9423853,9424302,9426282&sver=3&ms=au&mt=1450796472&mv=m&pl=24&key=yt6&mm=31&mn=sn-5hne6n7k&id=o-AKM5y7gcniGyb9CVFLJVmvg45M5RxZ715TF8iPLeYnU5&dur=315.652&source=youtube&ip=213.162.159.78&requiressl=yes&lmt=1448576751409233&itag=17&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,requiressl,source,upn,expire&signature=83D43D5317A394F276F479CD46713D7E5D259D88411.19A7FB966F9A1229054599815FA7FAA24E141CA4CA4', 'container': '3gp', 'audio': {'bitrate': 24, 'encoding': 'aac'}} 16:04:34 T:140519778805504 DEBUG: [plugin.video.youtube] selected stream: {'sort': [720, 0], 'video': {'encoding': 'h.264', 'height': 720}, 'title': '[B]720p[/B] (mp4;h.264 / aac@192)', 'url': 'https://r5---sn-5hne6n7k.googlevideo.com/videoplayback?ipbits=0&ratebypass=yes&initcwndbps=556250&expire=1450818273&upn=-3F-6-sjP2M&mime=video/mp4&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&fexp=9407051,9410705,9415327,9416126,9418400,9418750,9419452,9419549,9420452,9421249,9422596,9423293,9423662,9423853,9424302,9426282&sver=3&ms=au&mt=1450796472&mv=m&pl=24&key=yt6&mm=31&mn=sn-5hne6n7k&id=o-AKM5y7gcniGyb9CVFLJVmvg45M5RxZ715TF8iPLeYnU5&dur=315.628&source=youtube&ip=213.162.159.78&requiressl=yes&lmt=1448866065137762&itag=22&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&signature=B79A79252830FA9508F542F01BAA5095089DC120995.D308810D06122EE69C5C4F5EEED41CF706D89C7CC7C', 'container': 'mp4', 'audio': {'bitrate': 192, 'encoding': 'aac'}} 16:04:34 T:140519778805504 DEBUG: [plugin.video.youtube] Selecting YouTube config "Isengard" 16:04:34 T:140519778805504 DEBUG: [plugin.video.youtube] User is not logged in 16:04:34 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'QtXby3twMmI' cached 16:04:35 T:140519778805504 DEBUG: POParser: loaded 100 strings from file /home/ruschi/.kodi/addons/plugin.video.youtube/resources/language/German/strings.po 16:04:35 T:140519778805504 DEBUG: POParser: loaded 0 strings from file /home/ruschi/.kodi/addons/plugin.video.youtube/resources/language/English/strings.po 16:04:35 T:140519778805504 DEBUG: [plugin.video.youtube] Converting VideoItem 16:04:35 T:140519778805504 DEBUG: [plugin.video.youtube] Shutdown of Kodion 16:04:35 T:140519778805504 INFO: CPythonInvoker(20, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): script successfully run 16:04:35 T:140520737499072 DEBUG: WaitOnScriptResult- plugin returned successfully 16:04:35 T:140520737499072 INFO: easy_aquire - Created session to https://r5---sn-5hne6n7k.googlevideo.com 16:04:35 T:140519778805504 INFO: Python script stopped 16:04:35 T:140519778805504 DEBUG: Thread LanguageInvoker 140519778805504 terminating 16:04:35 T:140517731497728 INFO: CPythonInvoker(15, /home/ruschi/.kodi/addons/script.tvtunes/resources/lib/upload.py): script successfully run 16:04:35 T:140517731497728 INFO: Python script stopped 16:04:35 T:140517731497728 DEBUG: Thread LanguageInvoker 140517731497728 terminating 16:04:35 T:140520737499072 ERROR: CCurlFile::Stat - Failed: HTTP response code said error(22) for https://r5---sn-5hne6n7k.googlevideo.com/videoplayback?ipbits=0&ratebypass=yes&initcwndbps=556250&expire=1450818273&upn=-3F-6-sjP2M&mime=video/mp4&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&fexp=9407051,9410705,9415327,9416126,9418400,9418750,9419452,9419549,9420452,9421249,9422596,9423293,9423662,9423853,9424302,9426282&sver=3&ms=au&mt=1450796472&mv=m&pl=24&key=yt6&mm=31&mn=sn-5hne6n7k&id=o-AKM5y7gcniGyb9CVFLJVmvg45M5RxZ715TF8iPLeYnU5&dur=315.628&source=youtube&ip=213.162.159.78&requiressl=yes&lmt=1448866065137762&itag=22&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&signature=B79A79252830FA9508F542F01BAA5095089DC120995.D308810D06122EE69C5C4F5EEED41CF706D89C7CC7C 16:04:35 T:140520737499072 DEBUG: CCurlFile::GetMimeType - https://r5---sn-5hne6n7k.googlevideo.com/videoplayback?ipbits=0&ratebypass=yes&initcwndbps=556250&expire=1450818273&upn=-3F-6-sjP2M&mime=video/mp4&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&fexp=9407051,9410705,9415327,9416126,9418400,9418750,9419452,9419549,9420452,9421249,9422596,9423293,9423662,9423853,9424302,9426282&sver=3&ms=au&mt=1450796472&mv=m&pl=24&key=yt6&mm=31&mn=sn-5hne6n7k&id=o-AKM5y7gcniGyb9CVFLJVmvg45M5RxZ715TF8iPLeYnU5&dur=315.628&source=youtube&ip=213.162.159.78&requiressl=yes&lmt=1448866065137762&itag=22&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&signature=B79A79252830FA9508F542F01BAA5095089DC120995.D308810D06122EE69C5C4F5EEED41CF706D89C7CC7C -> failed 16:04:35 T:140520737499072 DEBUG: Loading settings for https://r5---sn-5hne6n7k.googlevideo.com/videoplayback?ipbits=0&ratebypass=yes&initcwndbps=556250&expire=1450818273&upn=-3F-6-sjP2M&mime=video/mp4&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&fexp=9407051,9410705,9415327,9416126,9418400,9418750,9419452,9419549,9420452,9421249,9422596,9423293,9423662,9423853,9424302,9426282&sver=3&ms=au&mt=1450796472&mv=m&pl=24&key=yt6&mm=31&mn=sn-5hne6n7k&id=o-AKM5y7gcniGyb9CVFLJVmvg45M5RxZ715TF8iPLeYnU5&dur=315.628&source=youtube&ip=213.162.159.78&requiressl=yes&lmt=1448866065137762&itag=22&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&signature=B79A79252830FA9508F542F01BAA5095089DC120995.D308810D06122EE69C5C4F5EEED41CF706D89C7CC7C 16:04:35 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers(https://r5---sn-5hne6n7k.googlevideo.com/videoplayback?ipbits=0&ratebypass=yes&initcwndbps=556250&expire=1450818273&upn=-3F-6-sjP2M&mime=video/mp4&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&fexp=9407051,9410705,9415327,9416126,9418400,9418750,9419452,9419549,9420452,9421249,9422596,9423293,9423662,9423853,9424302,9426282&sver=3&ms=au&mt=1450796472&mv=m&pl=24&key=yt6&mm=31&mn=sn-5hne6n7k&id=o-AKM5y7gcniGyb9CVFLJVmvg45M5RxZ715TF8iPLeYnU5&dur=315.628&source=youtube&ip=213.162.159.78&requiressl=yes&lmt=1448866065137762&itag=22&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&signature=B79A79252830FA9508F542F01BAA5095089DC120995.D308810D06122EE69C5C4F5EEED41CF706D89C7CC7C) 16:04:35 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 16:04:35 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 16:04:35 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/mms/udp 16:04:35 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 16:04:35 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 16:04:35 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 16:04:35 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 16:04:35 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams 16:04:35 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp 16:04:35 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2 16:04:35 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 16:04:35 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 16:04:35 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 16:04:35 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 16:04:35 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 16:04:35 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 16:04:35 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1) 16:04:35 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 16:04:35 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 16:04:35 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1) 16:04:35 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 16:04:35 T:140520737499072 NOTICE: DVDPlayer: Opening: https://r5---sn-5hne6n7k.googlevideo.com/videoplayback?ipbits=0&ratebypass=yes&initcwndbps=556250&expire=1450818273&upn=-3F-6-sjP2M&mime=video/mp4&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&fexp=9407051,9410705,9415327,9416126,9418400,9418750,9419452,9419549,9420452,9421249,9422596,9423293,9423662,9423853,9424302,9426282&sver=3&ms=au&mt=1450796472&mv=m&pl=24&key=yt6&mm=31&mn=sn-5hne6n7k&id=o-AKM5y7gcniGyb9CVFLJVmvg45M5RxZ715TF8iPLeYnU5&dur=315.628&source=youtube&ip=213.162.159.78&requiressl=yes&lmt=1448866065137762&itag=22&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&signature=B79A79252830FA9508F542F01BAA5095089DC120995.D308810D06122EE69C5C4F5EEED41CF706D89C7CC7C 16:04:35 T:140520737499072 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 16:04:35 T:140520737499072 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec 16:04:35 T:140520737499072 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:04:35 T:140520737499072 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 16:04:35 T:140517731497728 NOTICE: Thread DVDPlayer start, auto delete: false 16:04:35 T:140517731497728 NOTICE: Creating InputStream 16:04:36 T:140520737499072 DEBUG: ------ Window Init (DialogBusy.xml) ------ 16:04:36 T:140517731497728 ERROR: CCurlFile::Stat - Failed: HTTP response code said error(22) for https://r5---sn-5hne6n7k.googlevideo.com/videoplayback?ipbits=0&ratebypass=yes&initcwndbps=556250&expire=1450818273&upn=-3F-6-sjP2M&mime=video/mp4&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&fexp=9407051,9410705,9415327,9416126,9418400,9418750,9419452,9419549,9420452,9421249,9422596,9423293,9423662,9423853,9424302,9426282&sver=3&ms=au&mt=1450796472&mv=m&pl=24&key=yt6&mm=31&mn=sn-5hne6n7k&id=o-AKM5y7gcniGyb9CVFLJVmvg45M5RxZ715TF8iPLeYnU5&dur=315.628&source=youtube&ip=213.162.159.78&requiressl=yes&lmt=1448866065137762&itag=22&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&signature=B79A79252830FA9508F542F01BAA5095089DC120995.D308810D06122EE69C5C4F5EEED41CF706D89C7CC7C 16:04:36 T:140517731497728 DEBUG: CCurlFile::GetMimeType - https://r5---sn-5hne6n7k.googlevideo.com/videoplayback?ipbits=0&ratebypass=yes&initcwndbps=556250&expire=1450818273&upn=-3F-6-sjP2M&mime=video/mp4&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&fexp=9407051,9410705,9415327,9416126,9418400,9418750,9419452,9419549,9420452,9421249,9422596,9423293,9423662,9423853,9424302,9426282&sver=3&ms=au&mt=1450796472&mv=m&pl=24&key=yt6&mm=31&mn=sn-5hne6n7k&id=o-AKM5y7gcniGyb9CVFLJVmvg45M5RxZ715TF8iPLeYnU5&dur=315.628&source=youtube&ip=213.162.159.78&requiressl=yes&lmt=1448866065137762&itag=22&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&signature=B79A79252830FA9508F542F01BAA5095089DC120995.D308810D06122EE69C5C4F5EEED41CF706D89C7CC7C -> failed 16:04:36 T:140517731497728 DEBUG: CFileCache::Open - opening using cache 16:04:36 T:140517731497728 DEBUG: CurlFile::Open(0x7fcce0074230) https://r5---sn-5hne6n7k.googlevideo.com/videoplayback?ipbits=0&ratebypass=yes&initcwndbps=556250&expire=1450818273&upn=-3F-6-sjP2M&mime=video/mp4&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&fexp=9407051,9410705,9415327,9416126,9418400,9418750,9419452,9419549,9420452,9421249,9422596,9423293,9423662,9423853,9424302,9426282&sver=3&ms=au&mt=1450796472&mv=m&pl=24&key=yt6&mm=31&mn=sn-5hne6n7k&id=o-AKM5y7gcniGyb9CVFLJVmvg45M5RxZ715TF8iPLeYnU5&dur=315.628&source=youtube&ip=213.162.159.78&requiressl=yes&lmt=1448866065137762&itag=22&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&signature=B79A79252830FA9508F542F01BAA5095089DC120995.D308810D06122EE69C5C4F5EEED41CF706D89C7CC7C 16:04:36 T:140520737499072 DEBUG: ------ Window Deinit (DialogProgress.xml) ------ 16:04:36 T:140517731497728 ERROR: CCurlFile::FillBuffer - Failed: HTTP returned error 403 16:04:36 T:140517731497728 ERROR: CCurlFile::Open failed with code 403 for https://r5---sn-5hne6n7k.googlevideo.com/videoplayback?ipbits=0&ratebypass=yes&initcwndbps=556250&expire=1450818273&upn=-3F-6-sjP2M&mime=video/mp4&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&fexp=9407051,9410705,9415327,9416126,9418400,9418750,9419452,9419549,9420452,9421249,9422596,9423293,9423662,9423853,9424302,9426282&sver=3&ms=au&mt=1450796472&mv=m&pl=24&key=yt6&mm=31&mn=sn-5hne6n7k&id=o-AKM5y7gcniGyb9CVFLJVmvg45M5RxZ715TF8iPLeYnU5&dur=315.628&source=youtube&ip=213.162.159.78&requiressl=yes&lmt=1448866065137762&itag=22&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&signature=B79A79252830FA9508F542F01BAA5095089DC120995.D308810D06122EE69C5C4F5EEED41CF706D89C7CC7C 16:04:36 T:140517731497728 ERROR: Open - failed to open source 16:04:36 T:140517731497728 ERROR: CDVDPlayer::OpenInputStream - error opening [https://r5---sn-5hne6n7k.googlevideo.com/videoplayback?ipbits=0&ratebypass=yes&initcwndbps=556250&expire=1450818273&upn=-3F-6-sjP2M&mime=video/mp4&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&fexp=9407051,9410705,9415327,9416126,9418400,9418750,9419452,9419549,9420452,9421249,9422596,9423293,9423662,9423853,9424302,9426282&sver=3&ms=au&mt=1450796472&mv=m&pl=24&key=yt6&mm=31&mn=sn-5hne6n7k&id=o-AKM5y7gcniGyb9CVFLJVmvg45M5RxZ715TF8iPLeYnU5&dur=315.628&source=youtube&ip=213.162.159.78&requiressl=yes&lmt=1448866065137762&itag=22&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&signature=B79A79252830FA9508F542F01BAA5095089DC120995.D308810D06122EE69C5C4F5EEED41CF706D89C7CC7C] 16:04:36 T:140517731497728 NOTICE: CDVDPlayer::OnExit() 16:04:36 T:140517731497728 DEBUG: OnPlayBackStopped: play state was 1, starting 1 16:04:36 T:140517731497728 DEBUG: Thread DVDPlayer 140517731497728 terminating 16:04:36 T:140520737499072 DEBUG: OnPlayBackStopped: play state was 3, starting 0 16:04:36 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 16:04:36 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 16:04:36 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Player, sender=xbmc, message=OnStop) 16:04:36 T:140520737499072 ERROR: Playlist Player: skipping unplayable item: 0, path [plugin://plugin.video.youtube/play/?video_id=QtXby3twMmI] 16:04:36 T:140520737499072 DEBUG: Playlist Player: no more playable items... aborting playback 16:04:36 T:140517731497728 NOTICE: Thread BackgroundLoader start, auto delete: false 16:04:36 T:140520737499072 NOTICE: CDVDPlayer::CloseFile() 16:04:36 T:140520737499072 NOTICE: DVDPlayer: waiting for threads to exit 16:04:36 T:140520737499072 NOTICE: DVDPlayer: finished waiting 16:04:36 T:140520737499072 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:04:36 T:140517731497728 DEBUG: Thread BackgroundLoader 140517731497728 terminating 16:04:36 T:140520737499072 NOTICE: CDVDPlayer::CloseFile() 16:04:36 T:140520737499072 NOTICE: DVDPlayer: waiting for threads to exit 16:04:36 T:140520737499072 NOTICE: DVDPlayer: finished waiting 16:04:36 T:140520737499072 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:04:36 T:140517857793792 DEBUG: DoWork - Saving file state for video item plugin://plugin.video.youtube/play/?video_id=QtXby3twMmI 16:04:36 T:140520737499072 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 16:04:41 T:140520737499072 DEBUG: UPower: Received an unknown signal DeviceChanged 16:04:41 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:04:41 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:04:43 T:140520737499072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:04:43 T:140520737499072 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:04:43 T:140520737499072 DEBUG: OnPlayMedia plugin://plugin.video.youtube/play/?video_id=VPRjCeoBqrI 16:04:43 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 16:04:43 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 16:04:43 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Playlist, sender=xbmc, message=OnClear) 16:04:43 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:04:43 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:04:43 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Playlist, sender=xbmc, message=OnAdd) 16:04:43 T:140520737499072 DEBUG: StartScript - calling plugin YouTube('plugin://plugin.video.youtube/play/','7','?video_id=VPRjCeoBqrI') 16:04:43 T:140520737499072 DEBUG: WaitOnScriptResult - waiting on the YouTube (id=21) plugin... 16:04:43 T:140517731497728 NOTICE: Thread LanguageInvoker start, auto delete: false 16:04:43 T:140517731497728 INFO: initializing python engine. 16:04:43 T:140517731497728 DEBUG: CPythonInvoker(21, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): start processing 16:04:43 T:140517731497728 NOTICE: -->Python Interpreter Initialized<-- 16:04:43 T:140517731497728 DEBUG: CPythonInvoker(21, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): the source file to load is "/home/ruschi/.kodi/addons/plugin.video.youtube/default.py" 16:04:43 T:140517731497728 DEBUG: CPythonInvoker(21, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): setting the Python path to /home/ruschi/.kodi/addons/plugin.video.youtube:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0 16:04:43 T:140517731497728 DEBUG: CPythonInvoker(21, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): entering source directory /home/ruschi/.kodi/addons/plugin.video.youtube 16:04:43 T:140517731497728 DEBUG: CPythonInvoker(21, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): instantiating addon using automatically obtained id of "plugin.video.youtube" dependent on version 2.1.0 of the xbmc.python api 16:04:43 T:140517731497728 DEBUG: [plugin.video.youtube] Starting Kodion framework by bromix... 16:04:43 T:140517731497728 NOTICE: [plugin.video.youtube] Running: YouTube (5.1.13) on Isengard (15.2) with Python 2.7.6 16:04:43 T:140517731497728 DEBUG: [plugin.video.youtube] Path: "/play/ 16:04:43 T:140517731497728 DEBUG: [plugin.video.youtube] Params: "{'video_id': u'VPRjCeoBqrI'}" 16:04:43 T:140517731497728 DEBUG: [plugin.video.youtube] Selecting YouTube config "Isengard" 16:04:43 T:140517731497728 DEBUG: [plugin.video.youtube] User is not logged in 16:04:44 T:140517731497728 DEBUG: [plugin.video.youtube] Shutdown of Kodion 16:04:44 T:140517731497728 INFO: CPythonInvoker(21, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): script successfully run 16:04:44 T:140520737499072 DEBUG: ------ Window Init (DialogKaiToast.xml) ------ 16:04:44 T:140517731497728 INFO: Python script stopped 16:04:44 T:140517731497728 DEBUG: Thread LanguageInvoker 140517731497728 terminating 16:04:44 T:140520737499072 DEBUG: WaitOnScriptResult - plugin exited prematurely - terminating 16:04:44 T:140520737499072 ERROR: Playlist Player: skipping unplayable item: 0, path [plugin://plugin.video.youtube/play/?video_id=VPRjCeoBqrI] 16:04:44 T:140520737499072 DEBUG: Playlist Player: no more playable items... aborting playback 16:04:44 T:140517731497728 NOTICE: Thread BackgroundLoader start, auto delete: false 16:04:44 T:140517731497728 DEBUG: Thread BackgroundLoader 140517731497728 terminating 16:04:44 T:140520737499072 INFO: CheckIdle - Closing session to http://www.msftncsi.com (easy=0x7fccf8010e40, multi=(nil)) 16:04:46 T:140520737499072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:04:46 T:140520737499072 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:04:46 T:140520737499072 DEBUG: OnPlayMedia plugin://plugin.video.youtube/play/?video_id=VPRjCeoBqrI 16:04:46 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 16:04:46 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 16:04:46 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Playlist, sender=xbmc, message=OnClear) 16:04:46 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:04:46 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:04:46 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Playlist, sender=xbmc, message=OnAdd) 16:04:46 T:140520737499072 DEBUG: StartScript - calling plugin YouTube('plugin://plugin.video.youtube/play/','8','?video_id=VPRjCeoBqrI') 16:04:46 T:140520737499072 DEBUG: WaitOnScriptResult - waiting on the YouTube (id=22) plugin... 16:04:46 T:140517731497728 NOTICE: Thread LanguageInvoker start, auto delete: false 16:04:46 T:140517731497728 INFO: initializing python engine. 16:04:46 T:140517731497728 DEBUG: CPythonInvoker(22, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): start processing 16:04:46 T:140517731497728 NOTICE: -->Python Interpreter Initialized<-- 16:04:46 T:140517731497728 DEBUG: CPythonInvoker(22, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): the source file to load is "/home/ruschi/.kodi/addons/plugin.video.youtube/default.py" 16:04:46 T:140517731497728 DEBUG: CPythonInvoker(22, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): setting the Python path to /home/ruschi/.kodi/addons/plugin.video.youtube:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0 16:04:46 T:140517731497728 DEBUG: CPythonInvoker(22, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): entering source directory /home/ruschi/.kodi/addons/plugin.video.youtube 16:04:46 T:140517731497728 DEBUG: CPythonInvoker(22, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): instantiating addon using automatically obtained id of "plugin.video.youtube" dependent on version 2.1.0 of the xbmc.python api 16:04:46 T:140517731497728 DEBUG: [plugin.video.youtube] Starting Kodion framework by bromix... 16:04:46 T:140517731497728 NOTICE: [plugin.video.youtube] Running: YouTube (5.1.13) on Isengard (15.2) with Python 2.7.6 16:04:46 T:140517731497728 DEBUG: [plugin.video.youtube] Path: "/play/ 16:04:46 T:140517731497728 DEBUG: [plugin.video.youtube] Params: "{'video_id': u'VPRjCeoBqrI'}" 16:04:46 T:140517731497728 DEBUG: [plugin.video.youtube] Selecting YouTube config "Isengard" 16:04:46 T:140517731497728 DEBUG: [plugin.video.youtube] User is not logged in 16:04:46 T:140520737499072 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86_64-linux.so) 16:04:46 T:140520737499072 DEBUG: Unloading: /ImageLib-x86_64-linux.so 16:04:47 T:140517731497728 DEBUG: [plugin.video.youtube] Shutdown of Kodion 16:04:47 T:140517731497728 INFO: CPythonInvoker(22, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): script successfully run 16:04:47 T:140517731497728 INFO: Python script stopped 16:04:47 T:140517731497728 DEBUG: Thread LanguageInvoker 140517731497728 terminating 16:04:47 T:140520737499072 DEBUG: WaitOnScriptResult - plugin exited prematurely - terminating 16:04:47 T:140520737499072 ERROR: Playlist Player: skipping unplayable item: 0, path [plugin://plugin.video.youtube/play/?video_id=VPRjCeoBqrI] 16:04:47 T:140520737499072 DEBUG: Playlist Player: no more playable items... aborting playback 16:04:47 T:140517731497728 NOTICE: Thread BackgroundLoader start, auto delete: false 16:04:47 T:140517731497728 DEBUG: Thread BackgroundLoader 140517731497728 terminating 16:04:48 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:04:48 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:04:50 T:140520737499072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:04:50 T:140520737499072 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:04:50 T:140520737499072 DEBUG: OnPlayMedia plugin://plugin.video.youtube/play/?video_id=1G4isv_Fylg 16:04:50 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 16:04:50 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 16:04:50 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Playlist, sender=xbmc, message=OnClear) 16:04:50 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:04:50 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:04:50 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Playlist, sender=xbmc, message=OnAdd) 16:04:50 T:140520737499072 DEBUG: StartScript - calling plugin YouTube('plugin://plugin.video.youtube/play/','9','?video_id=1G4isv_Fylg') 16:04:50 T:140520737499072 DEBUG: WaitOnScriptResult - waiting on the YouTube (id=23) plugin... 16:04:50 T:140517731497728 NOTICE: Thread LanguageInvoker start, auto delete: false 16:04:50 T:140517731497728 INFO: initializing python engine. 16:04:50 T:140517731497728 DEBUG: CPythonInvoker(23, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): start processing 16:04:50 T:140517731497728 NOTICE: -->Python Interpreter Initialized<-- 16:04:50 T:140517731497728 DEBUG: CPythonInvoker(23, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): the source file to load is "/home/ruschi/.kodi/addons/plugin.video.youtube/default.py" 16:04:50 T:140517731497728 DEBUG: CPythonInvoker(23, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): setting the Python path to /home/ruschi/.kodi/addons/plugin.video.youtube:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0 16:04:50 T:140517731497728 DEBUG: CPythonInvoker(23, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): entering source directory /home/ruschi/.kodi/addons/plugin.video.youtube 16:04:50 T:140517731497728 DEBUG: CPythonInvoker(23, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): instantiating addon using automatically obtained id of "plugin.video.youtube" dependent on version 2.1.0 of the xbmc.python api 16:04:50 T:140517731497728 DEBUG: [plugin.video.youtube] Starting Kodion framework by bromix... 16:04:50 T:140517731497728 NOTICE: [plugin.video.youtube] Running: YouTube (5.1.13) on Isengard (15.2) with Python 2.7.6 16:04:50 T:140517731497728 DEBUG: [plugin.video.youtube] Path: "/play/ 16:04:50 T:140517731497728 DEBUG: [plugin.video.youtube] Params: "{'video_id': u'1G4isv_Fylg'}" 16:04:50 T:140517731497728 DEBUG: [plugin.video.youtube] Selecting YouTube config "Isengard" 16:04:50 T:140517731497728 DEBUG: [plugin.video.youtube] User is not logged in 16:04:51 T:140517731497728 DEBUG: [plugin.video.youtube] Shutdown of Kodion 16:04:51 T:140517731497728 INFO: CPythonInvoker(23, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): script successfully run 16:04:51 T:140517731497728 INFO: Python script stopped 16:04:51 T:140517731497728 DEBUG: Thread LanguageInvoker 140517731497728 terminating 16:04:51 T:140520737499072 DEBUG: WaitOnScriptResult - plugin exited prematurely - terminating 16:04:51 T:140520737499072 ERROR: Playlist Player: skipping unplayable item: 0, path [plugin://plugin.video.youtube/play/?video_id=1G4isv_Fylg] 16:04:51 T:140520737499072 DEBUG: Playlist Player: no more playable items... aborting playback 16:04:51 T:140517731497728 NOTICE: Thread BackgroundLoader start, auto delete: false 16:04:51 T:140517731497728 DEBUG: Thread BackgroundLoader 140517731497728 terminating 16:04:54 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:04:54 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:04:57 T:140520737499072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:04:57 T:140520737499072 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:04:57 T:140520737499072 DEBUG: OnPlayMedia plugin://plugin.video.youtube/play/?video_id=RB-RcX5DS5A 16:04:57 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 16:04:57 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 16:04:57 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Playlist, sender=xbmc, message=OnClear) 16:04:57 T:140520737499072 DEBUG: GetMovieId (plugin://plugin.video.youtube/play/?video_id=RB-RcX5DS5A), query = select idMovie from movie where idFile=264 16:04:57 T:140520737499072 DEBUG: GetEpisodeId (plugin://plugin.video.youtube/play/?video_id=RB-RcX5DS5A), query = select idEpisode from episode where idFile=264 16:04:57 T:140520737499072 DEBUG: GetMusicVideoId (plugin://plugin.video.youtube/play/?video_id=RB-RcX5DS5A), query = select idMVideo from musicvideo where idFile=264 16:04:57 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:04:57 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:04:57 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Playlist, sender=xbmc, message=OnAdd) 16:04:57 T:140520737499072 DEBUG: StartScript - calling plugin YouTube('plugin://plugin.video.youtube/play/','10','?video_id=RB-RcX5DS5A') 16:04:57 T:140520737499072 DEBUG: WaitOnScriptResult - waiting on the YouTube (id=24) plugin... 16:04:57 T:140517731497728 NOTICE: Thread LanguageInvoker start, auto delete: false 16:04:57 T:140517731497728 INFO: initializing python engine. 16:04:57 T:140517731497728 DEBUG: CPythonInvoker(24, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): start processing 16:04:57 T:140517731497728 NOTICE: -->Python Interpreter Initialized<-- 16:04:57 T:140517731497728 DEBUG: CPythonInvoker(24, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): the source file to load is "/home/ruschi/.kodi/addons/plugin.video.youtube/default.py" 16:04:57 T:140517731497728 DEBUG: CPythonInvoker(24, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): setting the Python path to /home/ruschi/.kodi/addons/plugin.video.youtube:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0 16:04:57 T:140517731497728 DEBUG: CPythonInvoker(24, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): entering source directory /home/ruschi/.kodi/addons/plugin.video.youtube 16:04:57 T:140517731497728 DEBUG: CPythonInvoker(24, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): instantiating addon using automatically obtained id of "plugin.video.youtube" dependent on version 2.1.0 of the xbmc.python api 16:04:57 T:140517731497728 DEBUG: [plugin.video.youtube] Starting Kodion framework by bromix... 16:04:57 T:140517731497728 NOTICE: [plugin.video.youtube] Running: YouTube (5.1.13) on Isengard (15.2) with Python 2.7.6 16:04:57 T:140517731497728 DEBUG: [plugin.video.youtube] Path: "/play/ 16:04:57 T:140517731497728 DEBUG: [plugin.video.youtube] Params: "{'video_id': u'RB-RcX5DS5A'}" 16:04:57 T:140517731497728 DEBUG: [plugin.video.youtube] Selecting YouTube config "Isengard" 16:04:57 T:140517731497728 DEBUG: [plugin.video.youtube] User is not logged in 16:04:59 T:140520737499072 DEBUG: DialogProgress::StartModal called 16:04:59 T:140520737499072 DEBUG: ------ Window Init (DialogProgress.xml) ------ 16:04:59 T:140517731497728 DEBUG: [plugin.video.youtube] selectable streams: 5 16:04:59 T:140517731497728 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [720, 0], 'video': {'encoding': 'h.264', 'height': 720}, 'title': '[B]720p[/B] (mp4;h.264 / aac@192)', 'url': 'https://r1---sn-5hnednez.googlevideo.com/videoplayback?ratebypass=yes&ip=213.162.159.78&mm=31&source=youtube&mn=sn-5hnednez&id=o-ABiJINjNiRDlB1y36s3q1hqb5IWpkD80P-WvbWI17-2S&ms=au&mt=1450796650&pl=24&mv=m&mime=video/mp4&dur=265.334&upn=cBFZzltEzeg&itag=22&ipbits=0&nh=IgpwcjAzLmFtczE1KgkxMjcuMC4wLjE&fexp=9407029,9416126,9417701,9419475,9419817,9420452,9421932,9422596,9422924,9423662,9423666,9424184,9424221&sver=3&requiressl=yes&key=yt6&initcwndbps=227500&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&expire=1450818298&lmt=1394256099237418&signature=44EA4E19FCE7EAD752B823E4B72D1DF81E705D0362D.AFE8986A2A98F5D0AC5677C2B5DEA759FA2B955F55F', 'container': 'mp4', 'audio': {'bitrate': 192, 'encoding': 'aac'}} 16:04:59 T:140517731497728 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [360, 0], 'video': {'encoding': 'h.264', 'height': 360}, 'title': '[B]360p[/B] (mp4;h.264 / aac@96)', 'url': 'https://r1---sn-5hnednez.googlevideo.com/videoplayback?ratebypass=yes&ip=213.162.159.78&mm=31&source=youtube&mn=sn-5hnednez&id=o-ABiJINjNiRDlB1y36s3q1hqb5IWpkD80P-WvbWI17-2S&ms=au&mt=1450796650&pl=24&mv=m&mime=video/mp4&dur=265.334&upn=cBFZzltEzeg&itag=18&ipbits=0&nh=IgpwcjAzLmFtczE1KgkxMjcuMC4wLjE&fexp=9407029,9416126,9417701,9419475,9419817,9420452,9421932,9422596,9422924,9423662,9423666,9424184,9424221&sver=3&requiressl=yes&key=yt6&initcwndbps=227500&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&expire=1450818298&lmt=1394255997953970&signature=A54D54F24F30BD602E631DDC1334A983D63D9335DCC.697A0D566E2C2CE5137160F573AA48E57AFC86BF6BF', 'container': 'mp4', 'audio': {'bitrate': 96, 'encoding': 'aac'}} 16:04:59 T:140517731497728 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [360, -1], 'video': {'encoding': 'vp8', 'height': 360}, 'title': '[B]360p[/B] (webm;vp8 / vorbis@128)', 'url': 'https://r1---sn-5hnednez.googlevideo.com/videoplayback?ratebypass=yes&ip=213.162.159.78&mm=31&source=youtube&mn=sn-5hnednez&id=o-ABiJINjNiRDlB1y36s3q1hqb5IWpkD80P-WvbWI17-2S&ms=au&mt=1450796650&pl=24&mv=m&mime=video/webm&dur=0.000&upn=cBFZzltEzeg&itag=43&ipbits=0&nh=IgpwcjAzLmFtczE1KgkxMjcuMC4wLjE&fexp=9407029,9416126,9417701,9419475,9419817,9420452,9421932,9422596,9422924,9423662,9423666,9424184,9424221&sver=3&requiressl=yes&key=yt6&initcwndbps=227500&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&expire=1450818298&lmt=1365725608579398&signature=0CC4CC61103C54D061FC88502D2613A3666D1468AE6.DA71011AC3C1873F694EE7E728EEA13D5D91921F21F', 'container': 'webm', 'audio': {'bitrate': 128, 'encoding': 'vorbis'}} 16:04:59 T:140517731497728 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [240, 0], 'video': {'encoding': 'h.263', 'height': 240}, 'title': '[B]240p[/B] (flv;h.263 / mp3@64)', 'url': 'https://r1---sn-5hnednez.googlevideo.com/videoplayback?ip=213.162.159.78&mm=31&source=youtube&mn=sn-5hnednez&id=o-ABiJINjNiRDlB1y36s3q1hqb5IWpkD80P-WvbWI17-2S&ms=au&mt=1450796650&pl=24&mv=m&mime=video/x-flv&dur=265.326&upn=cBFZzltEzeg&itag=5&ipbits=0&nh=IgpwcjAzLmFtczE1KgkxMjcuMC4wLjE&fexp=9407029,9416126,9417701,9419475,9419817,9420452,9421932,9422596,9422924,9423662,9423666,9424184,9424221&sver=3&requiressl=yes&key=yt6&initcwndbps=227500&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,requiressl,source,upn,expire&expire=1450818298&lmt=1394255968797382&signature=5774777E748FE4B47770934CFEB584779D21B7CA669.030EEFFC779B193B5AEE6ACD2E01EAA6D1F6BD15D15', 'container': 'flv', 'audio': {'bitrate': 64, 'encoding': 'mp3'}} 16:04:59 T:140517731497728 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [240, -20], 'video': {'encoding': 'mpeg-4', 'height': 240}, 'title': '[B]240p[/B] (3gp;mpeg-4 / aac@32)', 'url': 'https://r1---sn-5hnednez.googlevideo.com/videoplayback?ip=213.162.159.78&mm=31&source=youtube&mn=sn-5hnednez&id=o-ABiJINjNiRDlB1y36s3q1hqb5IWpkD80P-WvbWI17-2S&ms=au&mt=1450796650&pl=24&mv=m&mime=video/3gpp&dur=265.496&upn=cBFZzltEzeg&itag=36&ipbits=0&nh=IgpwcjAzLmFtczE1KgkxMjcuMC4wLjE&fexp=9407029,9416126,9417701,9419475,9419817,9420452,9421932,9422596,9422924,9423662,9423666,9424184,9424221&sver=3&requiressl=yes&key=yt6&initcwndbps=227500&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,requiressl,source,upn,expire&expire=1450818298&lmt=1427866904546776&signature=64CC4C7B281D2C7554C9CB52B9AFF185F645AEF3D6D.6A8CEB87E15D5232E726561FF458082C719AC3F83F8', 'container': '3gp', 'audio': {'bitrate': 32, 'encoding': 'aac'}} 16:04:59 T:140517731497728 DEBUG: [plugin.video.youtube] selected stream: {'sort': [720, 0], 'video': {'encoding': 'h.264', 'height': 720}, 'title': '[B]720p[/B] (mp4;h.264 / aac@192)', 'url': 'https://r1---sn-5hnednez.googlevideo.com/videoplayback?ratebypass=yes&ip=213.162.159.78&mm=31&source=youtube&mn=sn-5hnednez&id=o-ABiJINjNiRDlB1y36s3q1hqb5IWpkD80P-WvbWI17-2S&ms=au&mt=1450796650&pl=24&mv=m&mime=video/mp4&dur=265.334&upn=cBFZzltEzeg&itag=22&ipbits=0&nh=IgpwcjAzLmFtczE1KgkxMjcuMC4wLjE&fexp=9407029,9416126,9417701,9419475,9419817,9420452,9421932,9422596,9422924,9423662,9423666,9424184,9424221&sver=3&requiressl=yes&key=yt6&initcwndbps=227500&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&expire=1450818298&lmt=1394256099237418&signature=44EA4E19FCE7EAD752B823E4B72D1DF81E705D0362D.AFE8986A2A98F5D0AC5677C2B5DEA759FA2B955F55F', 'container': 'mp4', 'audio': {'bitrate': 192, 'encoding': 'aac'}} 16:04:59 T:140517731497728 DEBUG: [plugin.video.youtube] Selecting YouTube config "Isengard" 16:04:59 T:140517731497728 DEBUG: [plugin.video.youtube] User is not logged in 16:04:59 T:140517731497728 DEBUG: [plugin.video.youtube] No data for video 'RB-RcX5DS5A' cached 16:04:59 T:140517731497728 DEBUG: POParser: loaded 100 strings from file /home/ruschi/.kodi/addons/plugin.video.youtube/resources/language/German/strings.po 16:04:59 T:140517731497728 DEBUG: POParser: loaded 0 strings from file /home/ruschi/.kodi/addons/plugin.video.youtube/resources/language/English/strings.po 16:04:59 T:140517731497728 DEBUG: [plugin.video.youtube] Converting VideoItem 16:04:59 T:140517731497728 DEBUG: [plugin.video.youtube] Shutdown of Kodion 16:04:59 T:140517731497728 INFO: CPythonInvoker(24, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): script successfully run 16:04:59 T:140517731497728 INFO: Python script stopped 16:04:59 T:140517731497728 DEBUG: Thread LanguageInvoker 140517731497728 terminating 16:04:59 T:140520737499072 DEBUG: WaitOnScriptResult- plugin returned successfully 16:04:59 T:140520737499072 INFO: easy_aquire - Created session to https://r1---sn-5hnednez.googlevideo.com 16:05:00 T:140520737499072 ERROR: CCurlFile::Stat - Failed: HTTP response code said error(22) for https://r1---sn-5hnednez.googlevideo.com/videoplayback?ratebypass=yes&ip=213.162.159.78&mm=31&source=youtube&mn=sn-5hnednez&id=o-ABiJINjNiRDlB1y36s3q1hqb5IWpkD80P-WvbWI17-2S&ms=au&mt=1450796650&pl=24&mv=m&mime=video/mp4&dur=265.334&upn=cBFZzltEzeg&itag=22&ipbits=0&nh=IgpwcjAzLmFtczE1KgkxMjcuMC4wLjE&fexp=9407029,9416126,9417701,9419475,9419817,9420452,9421932,9422596,9422924,9423662,9423666,9424184,9424221&sver=3&requiressl=yes&key=yt6&initcwndbps=227500&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&expire=1450818298&lmt=1394256099237418&signature=44EA4E19FCE7EAD752B823E4B72D1DF81E705D0362D.AFE8986A2A98F5D0AC5677C2B5DEA759FA2B955F55F 16:05:00 T:140520737499072 DEBUG: CCurlFile::GetMimeType - https://r1---sn-5hnednez.googlevideo.com/videoplayback?ratebypass=yes&ip=213.162.159.78&mm=31&source=youtube&mn=sn-5hnednez&id=o-ABiJINjNiRDlB1y36s3q1hqb5IWpkD80P-WvbWI17-2S&ms=au&mt=1450796650&pl=24&mv=m&mime=video/mp4&dur=265.334&upn=cBFZzltEzeg&itag=22&ipbits=0&nh=IgpwcjAzLmFtczE1KgkxMjcuMC4wLjE&fexp=9407029,9416126,9417701,9419475,9419817,9420452,9421932,9422596,9422924,9423662,9423666,9424184,9424221&sver=3&requiressl=yes&key=yt6&initcwndbps=227500&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&expire=1450818298&lmt=1394256099237418&signature=44EA4E19FCE7EAD752B823E4B72D1DF81E705D0362D.AFE8986A2A98F5D0AC5677C2B5DEA759FA2B955F55F -> failed 16:05:00 T:140520737499072 DEBUG: Loading settings for https://r1---sn-5hnednez.googlevideo.com/videoplayback?ratebypass=yes&ip=213.162.159.78&mm=31&source=youtube&mn=sn-5hnednez&id=o-ABiJINjNiRDlB1y36s3q1hqb5IWpkD80P-WvbWI17-2S&ms=au&mt=1450796650&pl=24&mv=m&mime=video/mp4&dur=265.334&upn=cBFZzltEzeg&itag=22&ipbits=0&nh=IgpwcjAzLmFtczE1KgkxMjcuMC4wLjE&fexp=9407029,9416126,9417701,9419475,9419817,9420452,9421932,9422596,9422924,9423662,9423666,9424184,9424221&sver=3&requiressl=yes&key=yt6&initcwndbps=227500&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&expire=1450818298&lmt=1394256099237418&signature=44EA4E19FCE7EAD752B823E4B72D1DF81E705D0362D.AFE8986A2A98F5D0AC5677C2B5DEA759FA2B955F55F 16:05:00 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers(https://r1---sn-5hnednez.googlevideo.com/videoplayback?ratebypass=yes&ip=213.162.159.78&mm=31&source=youtube&mn=sn-5hnednez&id=o-ABiJINjNiRDlB1y36s3q1hqb5IWpkD80P-WvbWI17-2S&ms=au&mt=1450796650&pl=24&mv=m&mime=video/mp4&dur=265.334&upn=cBFZzltEzeg&itag=22&ipbits=0&nh=IgpwcjAzLmFtczE1KgkxMjcuMC4wLjE&fexp=9407029,9416126,9417701,9419475,9419817,9420452,9421932,9422596,9422924,9423662,9423666,9424184,9424221&sver=3&requiressl=yes&key=yt6&initcwndbps=227500&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&expire=1450818298&lmt=1394256099237418&signature=44EA4E19FCE7EAD752B823E4B72D1DF81E705D0362D.AFE8986A2A98F5D0AC5677C2B5DEA759FA2B955F55F) 16:05:00 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 16:05:00 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 16:05:00 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/mms/udp 16:05:00 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 16:05:00 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 16:05:00 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 16:05:00 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 16:05:00 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams 16:05:00 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp 16:05:00 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2 16:05:00 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 16:05:00 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 16:05:00 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 16:05:00 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 16:05:00 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 16:05:00 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 16:05:00 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1) 16:05:00 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 16:05:00 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 16:05:00 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1) 16:05:00 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 16:05:00 T:140520737499072 NOTICE: DVDPlayer: Opening: https://r1---sn-5hnednez.googlevideo.com/videoplayback?ratebypass=yes&ip=213.162.159.78&mm=31&source=youtube&mn=sn-5hnednez&id=o-ABiJINjNiRDlB1y36s3q1hqb5IWpkD80P-WvbWI17-2S&ms=au&mt=1450796650&pl=24&mv=m&mime=video/mp4&dur=265.334&upn=cBFZzltEzeg&itag=22&ipbits=0&nh=IgpwcjAzLmFtczE1KgkxMjcuMC4wLjE&fexp=9407029,9416126,9417701,9419475,9419817,9420452,9421932,9422596,9422924,9423662,9423666,9424184,9424221&sver=3&requiressl=yes&key=yt6&initcwndbps=227500&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&expire=1450818298&lmt=1394256099237418&signature=44EA4E19FCE7EAD752B823E4B72D1DF81E705D0362D.AFE8986A2A98F5D0AC5677C2B5DEA759FA2B955F55F 16:05:00 T:140520737499072 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 16:05:00 T:140520737499072 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec 16:05:00 T:140520737499072 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:05:00 T:140520737499072 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 16:05:00 T:140517731497728 NOTICE: Thread DVDPlayer start, auto delete: false 16:05:00 T:140517731497728 NOTICE: Creating InputStream 16:05:00 T:140520737499072 DEBUG: ------ Window Init (DialogBusy.xml) ------ 16:05:00 T:140517731497728 ERROR: CCurlFile::Stat - Failed: HTTP response code said error(22) for https://r1---sn-5hnednez.googlevideo.com/videoplayback?ratebypass=yes&ip=213.162.159.78&mm=31&source=youtube&mn=sn-5hnednez&id=o-ABiJINjNiRDlB1y36s3q1hqb5IWpkD80P-WvbWI17-2S&ms=au&mt=1450796650&pl=24&mv=m&mime=video/mp4&dur=265.334&upn=cBFZzltEzeg&itag=22&ipbits=0&nh=IgpwcjAzLmFtczE1KgkxMjcuMC4wLjE&fexp=9407029,9416126,9417701,9419475,9419817,9420452,9421932,9422596,9422924,9423662,9423666,9424184,9424221&sver=3&requiressl=yes&key=yt6&initcwndbps=227500&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&expire=1450818298&lmt=1394256099237418&signature=44EA4E19FCE7EAD752B823E4B72D1DF81E705D0362D.AFE8986A2A98F5D0AC5677C2B5DEA759FA2B955F55F 16:05:00 T:140517731497728 DEBUG: CCurlFile::GetMimeType - https://r1---sn-5hnednez.googlevideo.com/videoplayback?ratebypass=yes&ip=213.162.159.78&mm=31&source=youtube&mn=sn-5hnednez&id=o-ABiJINjNiRDlB1y36s3q1hqb5IWpkD80P-WvbWI17-2S&ms=au&mt=1450796650&pl=24&mv=m&mime=video/mp4&dur=265.334&upn=cBFZzltEzeg&itag=22&ipbits=0&nh=IgpwcjAzLmFtczE1KgkxMjcuMC4wLjE&fexp=9407029,9416126,9417701,9419475,9419817,9420452,9421932,9422596,9422924,9423662,9423666,9424184,9424221&sver=3&requiressl=yes&key=yt6&initcwndbps=227500&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&expire=1450818298&lmt=1394256099237418&signature=44EA4E19FCE7EAD752B823E4B72D1DF81E705D0362D.AFE8986A2A98F5D0AC5677C2B5DEA759FA2B955F55F -> failed 16:05:00 T:140517731497728 DEBUG: CFileCache::Open - opening using cache 16:05:00 T:140517731497728 DEBUG: CurlFile::Open(0x7fcce01e36a0) https://r1---sn-5hnednez.googlevideo.com/videoplayback?ratebypass=yes&ip=213.162.159.78&mm=31&source=youtube&mn=sn-5hnednez&id=o-ABiJINjNiRDlB1y36s3q1hqb5IWpkD80P-WvbWI17-2S&ms=au&mt=1450796650&pl=24&mv=m&mime=video/mp4&dur=265.334&upn=cBFZzltEzeg&itag=22&ipbits=0&nh=IgpwcjAzLmFtczE1KgkxMjcuMC4wLjE&fexp=9407029,9416126,9417701,9419475,9419817,9420452,9421932,9422596,9422924,9423662,9423666,9424184,9424221&sver=3&requiressl=yes&key=yt6&initcwndbps=227500&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&expire=1450818298&lmt=1394256099237418&signature=44EA4E19FCE7EAD752B823E4B72D1DF81E705D0362D.AFE8986A2A98F5D0AC5677C2B5DEA759FA2B955F55F 16:05:00 T:140520737499072 DEBUG: ------ Window Deinit (DialogProgress.xml) ------ 16:05:01 T:140517731497728 ERROR: CCurlFile::FillBuffer - Failed: HTTP returned error 403 16:05:01 T:140517731497728 ERROR: CCurlFile::Open failed with code 403 for https://r1---sn-5hnednez.googlevideo.com/videoplayback?ratebypass=yes&ip=213.162.159.78&mm=31&source=youtube&mn=sn-5hnednez&id=o-ABiJINjNiRDlB1y36s3q1hqb5IWpkD80P-WvbWI17-2S&ms=au&mt=1450796650&pl=24&mv=m&mime=video/mp4&dur=265.334&upn=cBFZzltEzeg&itag=22&ipbits=0&nh=IgpwcjAzLmFtczE1KgkxMjcuMC4wLjE&fexp=9407029,9416126,9417701,9419475,9419817,9420452,9421932,9422596,9422924,9423662,9423666,9424184,9424221&sver=3&requiressl=yes&key=yt6&initcwndbps=227500&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&expire=1450818298&lmt=1394256099237418&signature=44EA4E19FCE7EAD752B823E4B72D1DF81E705D0362D.AFE8986A2A98F5D0AC5677C2B5DEA759FA2B955F55F 16:05:01 T:140517731497728 ERROR: Open - failed to open source 16:05:01 T:140517731497728 ERROR: CDVDPlayer::OpenInputStream - error opening [https://r1---sn-5hnednez.googlevideo.com/videoplayback?ratebypass=yes&ip=213.162.159.78&mm=31&source=youtube&mn=sn-5hnednez&id=o-ABiJINjNiRDlB1y36s3q1hqb5IWpkD80P-WvbWI17-2S&ms=au&mt=1450796650&pl=24&mv=m&mime=video/mp4&dur=265.334&upn=cBFZzltEzeg&itag=22&ipbits=0&nh=IgpwcjAzLmFtczE1KgkxMjcuMC4wLjE&fexp=9407029,9416126,9417701,9419475,9419817,9420452,9421932,9422596,9422924,9423662,9423666,9424184,9424221&sver=3&requiressl=yes&key=yt6&initcwndbps=227500&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&expire=1450818298&lmt=1394256099237418&signature=44EA4E19FCE7EAD752B823E4B72D1DF81E705D0362D.AFE8986A2A98F5D0AC5677C2B5DEA759FA2B955F55F] 16:05:01 T:140517731497728 NOTICE: CDVDPlayer::OnExit() 16:05:01 T:140517731497728 DEBUG: OnPlayBackStopped: play state was 1, starting 1 16:05:01 T:140517731497728 DEBUG: Thread DVDPlayer 140517731497728 terminating 16:05:01 T:140520737499072 DEBUG: OnPlayBackStopped: play state was 3, starting 0 16:05:01 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 16:05:01 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 16:05:01 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Player, sender=xbmc, message=OnStop) 16:05:01 T:140520737499072 ERROR: Playlist Player: skipping unplayable item: 0, path [plugin://plugin.video.youtube/play/?video_id=RB-RcX5DS5A] 16:05:01 T:140520737499072 DEBUG: Playlist Player: one or more items failed to play... aborting playback 16:05:01 T:140520737499072 DEBUG: ------ Window Init (DialogOK.xml) ------ 16:05:01 T:140520737499072 INFO: Loading skin file: DialogOK.xml, load type: KEEP_IN_MEMORY 16:05:01 T:140520737499072 NOTICE: CDVDPlayer::CloseFile() 16:05:01 T:140520737499072 NOTICE: DVDPlayer: waiting for threads to exit 16:05:01 T:140520737499072 NOTICE: DVDPlayer: finished waiting 16:05:01 T:140520737499072 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:05:01 T:140520737499072 NOTICE: CDVDPlayer::CloseFile() 16:05:01 T:140520737499072 NOTICE: DVDPlayer: waiting for threads to exit 16:05:01 T:140520737499072 NOTICE: DVDPlayer: finished waiting 16:05:01 T:140520737499072 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:05:01 T:140519166433024 DEBUG: DoWork - Saving file state for video item plugin://plugin.video.youtube/play/?video_id=RB-RcX5DS5A 16:05:01 T:140520737499072 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 16:05:06 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:05:06 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:05:06 T:140520737499072 INFO: CheckIdle - Closing session to https://r5---sn-5hne6n7k.googlevideo.com (easy=0x90829f0, multi=0x7fcce00cb860) 16:05:06 T:140520737499072 DEBUG: ------ Window Deinit (DialogKaiToast.xml) ------ 16:05:07 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:05:07 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:05:07 T:140520737499072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:05:07 T:140520737499072 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:05:08 T:140520737499072 DEBUG: ------ Window Deinit (DialogOK.xml) ------ 16:05:08 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 16:05:08 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 16:05:08 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Playlist, sender=xbmc, message=OnClear) 16:05:08 T:140517731497728 NOTICE: Thread BackgroundLoader start, auto delete: false 16:05:08 T:140517731497728 DEBUG: Thread BackgroundLoader 140517731497728 terminating 16:05:08 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:05:08 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:05:09 T:140520737499072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:05:09 T:140520737499072 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:05:09 T:140520737499072 DEBUG: OnPlayMedia plugin://plugin.video.youtube/play/?video_id=P8jOQUsTU9o 16:05:09 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:05:09 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:05:09 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Playlist, sender=xbmc, message=OnAdd) 16:05:09 T:140520737499072 DEBUG: StartScript - calling plugin YouTube('plugin://plugin.video.youtube/play/','11','?video_id=P8jOQUsTU9o') 16:05:09 T:140520737499072 DEBUG: WaitOnScriptResult - waiting on the YouTube (id=25) plugin... 16:05:09 T:140517731497728 NOTICE: Thread LanguageInvoker start, auto delete: false 16:05:09 T:140517731497728 INFO: initializing python engine. 16:05:09 T:140517731497728 DEBUG: CPythonInvoker(25, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): start processing 16:05:09 T:140517731497728 NOTICE: -->Python Interpreter Initialized<-- 16:05:09 T:140517731497728 DEBUG: CPythonInvoker(25, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): the source file to load is "/home/ruschi/.kodi/addons/plugin.video.youtube/default.py" 16:05:09 T:140517731497728 DEBUG: CPythonInvoker(25, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): setting the Python path to /home/ruschi/.kodi/addons/plugin.video.youtube:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0 16:05:09 T:140517731497728 DEBUG: CPythonInvoker(25, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): entering source directory /home/ruschi/.kodi/addons/plugin.video.youtube 16:05:09 T:140517731497728 DEBUG: CPythonInvoker(25, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): instantiating addon using automatically obtained id of "plugin.video.youtube" dependent on version 2.1.0 of the xbmc.python api 16:05:09 T:140517731497728 DEBUG: [plugin.video.youtube] Starting Kodion framework by bromix... 16:05:09 T:140517731497728 NOTICE: [plugin.video.youtube] Running: YouTube (5.1.13) on Isengard (15.2) with Python 2.7.6 16:05:09 T:140517731497728 DEBUG: [plugin.video.youtube] Path: "/play/ 16:05:09 T:140517731497728 DEBUG: [plugin.video.youtube] Params: "{'video_id': u'P8jOQUsTU9o'}" 16:05:09 T:140517731497728 DEBUG: [plugin.video.youtube] Selecting YouTube config "Isengard" 16:05:09 T:140517731497728 DEBUG: [plugin.video.youtube] User is not logged in 16:05:09 T:140517731497728 DEBUG: [plugin.video.youtube] Shutdown of Kodion 16:05:09 T:140517731497728 INFO: CPythonInvoker(25, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): script successfully run 16:05:09 T:140520737499072 DEBUG: ------ Window Init (DialogKaiToast.xml) ------ 16:05:09 T:140517731497728 INFO: Python script stopped 16:05:09 T:140517731497728 DEBUG: Thread LanguageInvoker 140517731497728 terminating 16:05:10 T:140520737499072 DEBUG: WaitOnScriptResult - plugin exited prematurely - terminating 16:05:10 T:140520737499072 ERROR: Playlist Player: skipping unplayable item: 0, path [plugin://plugin.video.youtube/play/?video_id=P8jOQUsTU9o] 16:05:10 T:140520737499072 DEBUG: Playlist Player: no more playable items... aborting playback 16:05:10 T:140517731497728 NOTICE: Thread BackgroundLoader start, auto delete: false 16:05:10 T:140517731497728 DEBUG: Thread BackgroundLoader 140517731497728 terminating 16:05:11 T:140520737499072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:05:11 T:140520737499072 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:05:11 T:140520737499072 DEBUG: OnPlayMedia plugin://plugin.video.youtube/play/?video_id=P8jOQUsTU9o 16:05:11 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 16:05:11 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 16:05:11 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Playlist, sender=xbmc, message=OnClear) 16:05:11 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:05:11 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:05:11 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Playlist, sender=xbmc, message=OnAdd) 16:05:11 T:140520737499072 DEBUG: StartScript - calling plugin YouTube('plugin://plugin.video.youtube/play/','12','?video_id=P8jOQUsTU9o') 16:05:11 T:140520737499072 DEBUG: WaitOnScriptResult - waiting on the YouTube (id=26) plugin... 16:05:11 T:140517731497728 NOTICE: Thread LanguageInvoker start, auto delete: false 16:05:11 T:140517731497728 INFO: initializing python engine. 16:05:11 T:140517731497728 DEBUG: CPythonInvoker(26, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): start processing 16:05:11 T:140517731497728 NOTICE: -->Python Interpreter Initialized<-- 16:05:11 T:140517731497728 DEBUG: CPythonInvoker(26, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): the source file to load is "/home/ruschi/.kodi/addons/plugin.video.youtube/default.py" 16:05:11 T:140517731497728 DEBUG: CPythonInvoker(26, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): setting the Python path to /home/ruschi/.kodi/addons/plugin.video.youtube:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0 16:05:11 T:140517731497728 DEBUG: CPythonInvoker(26, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): entering source directory /home/ruschi/.kodi/addons/plugin.video.youtube 16:05:11 T:140517731497728 DEBUG: CPythonInvoker(26, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): instantiating addon using automatically obtained id of "plugin.video.youtube" dependent on version 2.1.0 of the xbmc.python api 16:05:11 T:140517731497728 DEBUG: [plugin.video.youtube] Starting Kodion framework by bromix... 16:05:11 T:140517731497728 NOTICE: [plugin.video.youtube] Running: YouTube (5.1.13) on Isengard (15.2) with Python 2.7.6 16:05:11 T:140517731497728 DEBUG: [plugin.video.youtube] Path: "/play/ 16:05:11 T:140517731497728 DEBUG: [plugin.video.youtube] Params: "{'video_id': u'P8jOQUsTU9o'}" 16:05:12 T:140517731497728 DEBUG: [plugin.video.youtube] Selecting YouTube config "Isengard" 16:05:12 T:140517731497728 DEBUG: [plugin.video.youtube] User is not logged in 16:05:12 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:05:12 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:05:12 T:140517731497728 DEBUG: [plugin.video.youtube] Shutdown of Kodion 16:05:12 T:140517731497728 INFO: CPythonInvoker(26, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): script successfully run 16:05:12 T:140517731497728 INFO: Python script stopped 16:05:12 T:140517731497728 DEBUG: Thread LanguageInvoker 140517731497728 terminating 16:05:12 T:140520737499072 DEBUG: WaitOnScriptResult - plugin exited prematurely - terminating 16:05:12 T:140520737499072 ERROR: Playlist Player: skipping unplayable item: 0, path [plugin://plugin.video.youtube/play/?video_id=P8jOQUsTU9o] 16:05:12 T:140520737499072 DEBUG: Playlist Player: no more playable items... aborting playback 16:05:12 T:140517731497728 NOTICE: Thread BackgroundLoader start, auto delete: false 16:05:12 T:140517731497728 DEBUG: Thread BackgroundLoader 140517731497728 terminating 16:05:14 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:05:14 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:05:15 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:05:15 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:05:16 T:140520737499072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:05:16 T:140520737499072 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:05:16 T:140520737499072 DEBUG: OnPlayMedia plugin://plugin.video.youtube/play/?video_id=Qtb11P1FWnc 16:05:16 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 16:05:16 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 16:05:16 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Playlist, sender=xbmc, message=OnClear) 16:05:16 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:05:16 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:05:16 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Playlist, sender=xbmc, message=OnAdd) 16:05:16 T:140520737499072 DEBUG: StartScript - calling plugin YouTube('plugin://plugin.video.youtube/play/','13','?video_id=Qtb11P1FWnc') 16:05:16 T:140520737499072 DEBUG: WaitOnScriptResult - waiting on the YouTube (id=27) plugin... 16:05:16 T:140517731497728 NOTICE: Thread LanguageInvoker start, auto delete: false 16:05:16 T:140517731497728 INFO: initializing python engine. 16:05:16 T:140517731497728 DEBUG: CPythonInvoker(27, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): start processing 16:05:16 T:140517731497728 NOTICE: -->Python Interpreter Initialized<-- 16:05:16 T:140517731497728 DEBUG: CPythonInvoker(27, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): the source file to load is "/home/ruschi/.kodi/addons/plugin.video.youtube/default.py" 16:05:16 T:140517731497728 DEBUG: CPythonInvoker(27, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): setting the Python path to /home/ruschi/.kodi/addons/plugin.video.youtube:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0 16:05:16 T:140517731497728 DEBUG: CPythonInvoker(27, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): entering source directory /home/ruschi/.kodi/addons/plugin.video.youtube 16:05:16 T:140517731497728 DEBUG: CPythonInvoker(27, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): instantiating addon using automatically obtained id of "plugin.video.youtube" dependent on version 2.1.0 of the xbmc.python api 16:05:16 T:140517731497728 DEBUG: [plugin.video.youtube] Starting Kodion framework by bromix... 16:05:16 T:140517731497728 NOTICE: [plugin.video.youtube] Running: YouTube (5.1.13) on Isengard (15.2) with Python 2.7.6 16:05:16 T:140517731497728 DEBUG: [plugin.video.youtube] Path: "/play/ 16:05:16 T:140517731497728 DEBUG: [plugin.video.youtube] Params: "{'video_id': u'Qtb11P1FWnc'}" 16:05:16 T:140517731497728 DEBUG: [plugin.video.youtube] Selecting YouTube config "Isengard" 16:05:16 T:140517731497728 DEBUG: [plugin.video.youtube] User is not logged in 16:05:18 T:140520737499072 DEBUG: DialogProgress::StartModal called 16:05:18 T:140520737499072 DEBUG: ------ Window Init (DialogProgress.xml) ------ 16:05:18 T:140517731497728 DEBUG: [plugin.video.youtube] selectable streams: 6 16:05:18 T:140517731497728 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [720, 0], 'video': {'encoding': 'h.264', 'height': 720}, 'title': '[B]720p[/B] (mp4;h.264 / aac@192)', 'url': 'https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?ratebypass=yes&itag=22&mime=video/mp4&initcwndbps=438750&fexp=9410705,9416126,9416984,9418045,9418201,9418401,9418777,9420309,9420452,9422432,9422596,9423038,9423662,9424213&ipbits=0&mt=1450796650&key=yt6&upn=H0oyL2-re48&expire=1450818317&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&ms=au&mv=m&source=youtube&pl=24&dur=315.512&lmt=1399587588314656&ip=213.162.159.78&id=o-AMAwqR0HAnUxjM_qnn99H9-pPrAZMT_la4pbsUPB2Mty&mn=sn-5hne6n7e&mm=31&sver=3&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&requiressl=yes&signature=347047F08F4D90C5C055AD8E48E1044AAD8AF985777.B44CD80B87D8F588F132F5E89150D3739EEE74C94C9', 'container': 'mp4', 'audio': {'bitrate': 192, 'encoding': 'aac'}} 16:05:18 T:140517731497728 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [360, 0], 'video': {'encoding': 'h.264', 'height': 360}, 'title': '[B]360p[/B] (mp4;h.264 / aac@96)', 'url': 'https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?ratebypass=yes&itag=18&mime=video/mp4&initcwndbps=438750&fexp=9410705,9416126,9416984,9418045,9418201,9418401,9418777,9420309,9420452,9422432,9422596,9423038,9423662,9424213&ipbits=0&mt=1450796650&key=yt6&upn=H0oyL2-re48&expire=1450818317&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&ms=au&mv=m&source=youtube&pl=24&dur=315.512&lmt=1399587556254155&ip=213.162.159.78&id=o-AMAwqR0HAnUxjM_qnn99H9-pPrAZMT_la4pbsUPB2Mty&mn=sn-5hne6n7e&mm=31&sver=3&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&requiressl=yes&signature=9DD3DDE43B668366FC3E55F6F03C4BE33E8B593A40E.7CAAB158FFBEC8D7283AA6F13960B5498C9F8279279', 'container': 'mp4', 'audio': {'bitrate': 96, 'encoding': 'aac'}} 16:05:18 T:140517731497728 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [360, -1], 'video': {'encoding': 'vp8', 'height': 360}, 'title': '[B]360p[/B] (webm;vp8 / vorbis@128)', 'url': 'https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?ratebypass=yes&itag=43&mime=video/webm&initcwndbps=438750&fexp=9410705,9416126,9416984,9418045,9418201,9418401,9418777,9420309,9420452,9422432,9422596,9423038,9423662,9424213&ipbits=0&mt=1450796650&key=yt6&upn=H0oyL2-re48&expire=1450818317&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&ms=au&mv=m&source=youtube&pl=24&dur=0.000&lmt=1396861852920802&ip=213.162.159.78&id=o-AMAwqR0HAnUxjM_qnn99H9-pPrAZMT_la4pbsUPB2Mty&mn=sn-5hne6n7e&mm=31&sver=3&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&requiressl=yes&signature=FCC4CCEEA20D8459B9FDC00F1BF51837A21339F44D5.57E46E27BD84EF5DBA079A0209A573B467C5317A17A', 'container': 'webm', 'audio': {'bitrate': 128, 'encoding': 'vorbis'}} 16:05:18 T:140517731497728 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [240, 0], 'video': {'encoding': 'h.263', 'height': 240}, 'title': '[B]240p[/B] (flv;h.263 / mp3@64)', 'url': 'https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?itag=5&mime=video/x-flv&initcwndbps=438750&fexp=9410705,9416126,9416984,9418045,9418201,9418401,9418777,9420309,9420452,9422432,9422596,9423038,9423662,9424213&ipbits=0&mt=1450796650&key=yt6&upn=H0oyL2-re48&expire=1450818317&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,requiressl,source,upn,expire&ms=au&mv=m&source=youtube&pl=24&dur=315.481&lmt=1396861351135557&ip=213.162.159.78&id=o-AMAwqR0HAnUxjM_qnn99H9-pPrAZMT_la4pbsUPB2Mty&mn=sn-5hne6n7e&mm=31&sver=3&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&requiressl=yes&signature=03D23D9CE35782DBBF696A44A9B4BCA0F16881BD12A.7E484047859B5D769876423CC52BEA0B6EA818C38C3', 'container': 'flv', 'audio': {'bitrate': 64, 'encoding': 'mp3'}} 16:05:18 T:140517731497728 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [240, -20], 'video': {'encoding': 'mpeg-4', 'height': 240}, 'title': '[B]240p[/B] (3gp;mpeg-4 / aac@32)', 'url': 'https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?itag=36&mime=video/3gpp&initcwndbps=438750&fexp=9410705,9416126,9416984,9418045,9418201,9418401,9418777,9420309,9420452,9422432,9422596,9423038,9423662,9424213&ipbits=0&mt=1450796650&key=yt6&upn=H0oyL2-re48&expire=1450818317&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,requiressl,source,upn,expire&ms=au&mv=m&source=youtube&pl=24&dur=315.652&lmt=1396861407423213&ip=213.162.159.78&id=o-AMAwqR0HAnUxjM_qnn99H9-pPrAZMT_la4pbsUPB2Mty&mn=sn-5hne6n7e&mm=31&sver=3&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&requiressl=yes&signature=A1A61A836F14C696CB28617D19A5C62DA04470617F5.71D2CBEB83F0F8826A1C12CD5FAE8A0F46B69485485', 'container': '3gp', 'audio': {'bitrate': 32, 'encoding': 'aac'}} 16:05:18 T:140517731497728 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [144, -20], 'video': {'encoding': 'mpeg-4', 'height': 144}, 'title': '[B]144p[/B] (3gp;mpeg-4 / aac@24)', 'url': 'https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?itag=17&mime=video/3gpp&initcwndbps=438750&fexp=9410705,9416126,9416984,9418045,9418201,9418401,9418777,9420309,9420452,9422432,9422596,9423038,9423662,9424213&ipbits=0&mt=1450796650&key=yt6&upn=H0oyL2-re48&expire=1450818317&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,requiressl,source,upn,expire&ms=au&mv=m&source=youtube&pl=24&dur=315.605&lmt=1396861414005259&ip=213.162.159.78&id=o-AMAwqR0HAnUxjM_qnn99H9-pPrAZMT_la4pbsUPB2Mty&mn=sn-5hne6n7e&mm=31&sver=3&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&requiressl=yes&signature=3647648A17330729A7AF2B20D32023978129071A257.952B0EBB401123C6237F41700A8258083F333A60A60', 'container': '3gp', 'audio': {'bitrate': 24, 'encoding': 'aac'}} 16:05:18 T:140517731497728 DEBUG: [plugin.video.youtube] selected stream: {'sort': [720, 0], 'video': {'encoding': 'h.264', 'height': 720}, 'title': '[B]720p[/B] (mp4;h.264 / aac@192)', 'url': 'https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?ratebypass=yes&itag=22&mime=video/mp4&initcwndbps=438750&fexp=9410705,9416126,9416984,9418045,9418201,9418401,9418777,9420309,9420452,9422432,9422596,9423038,9423662,9424213&ipbits=0&mt=1450796650&key=yt6&upn=H0oyL2-re48&expire=1450818317&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&ms=au&mv=m&source=youtube&pl=24&dur=315.512&lmt=1399587588314656&ip=213.162.159.78&id=o-AMAwqR0HAnUxjM_qnn99H9-pPrAZMT_la4pbsUPB2Mty&mn=sn-5hne6n7e&mm=31&sver=3&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&requiressl=yes&signature=347047F08F4D90C5C055AD8E48E1044AAD8AF985777.B44CD80B87D8F588F132F5E89150D3739EEE74C94C9', 'container': 'mp4', 'audio': {'bitrate': 192, 'encoding': 'aac'}} 16:05:18 T:140517731497728 DEBUG: [plugin.video.youtube] Selecting YouTube config "Isengard" 16:05:18 T:140517731497728 DEBUG: [plugin.video.youtube] User is not logged in 16:05:18 T:140517731497728 DEBUG: [plugin.video.youtube] No data for video 'Qtb11P1FWnc' cached 16:05:19 T:140517731497728 DEBUG: POParser: loaded 100 strings from file /home/ruschi/.kodi/addons/plugin.video.youtube/resources/language/German/strings.po 16:05:19 T:140517731497728 DEBUG: POParser: loaded 0 strings from file /home/ruschi/.kodi/addons/plugin.video.youtube/resources/language/English/strings.po 16:05:19 T:140517731497728 DEBUG: [plugin.video.youtube] Converting VideoItem 16:05:19 T:140517731497728 DEBUG: [plugin.video.youtube] Shutdown of Kodion 16:05:19 T:140520737499072 DEBUG: WaitOnScriptResult- plugin returned successfully 16:05:19 T:140517731497728 INFO: CPythonInvoker(27, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): script successfully run 16:05:19 T:140520737499072 INFO: easy_aquire - Created session to https://r4---sn-5hne6n7e.googlevideo.com 16:05:19 T:140517731497728 INFO: Python script stopped 16:05:19 T:140517731497728 DEBUG: Thread LanguageInvoker 140517731497728 terminating 16:05:19 T:140520737499072 ERROR: CCurlFile::Stat - Failed: HTTP response code said error(22) for https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?ratebypass=yes&itag=22&mime=video/mp4&initcwndbps=438750&fexp=9410705,9416126,9416984,9418045,9418201,9418401,9418777,9420309,9420452,9422432,9422596,9423038,9423662,9424213&ipbits=0&mt=1450796650&key=yt6&upn=H0oyL2-re48&expire=1450818317&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&ms=au&mv=m&source=youtube&pl=24&dur=315.512&lmt=1399587588314656&ip=213.162.159.78&id=o-AMAwqR0HAnUxjM_qnn99H9-pPrAZMT_la4pbsUPB2Mty&mn=sn-5hne6n7e&mm=31&sver=3&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&requiressl=yes&signature=347047F08F4D90C5C055AD8E48E1044AAD8AF985777.B44CD80B87D8F588F132F5E89150D3739EEE74C94C9 16:05:19 T:140520737499072 DEBUG: CCurlFile::GetMimeType - https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?ratebypass=yes&itag=22&mime=video/mp4&initcwndbps=438750&fexp=9410705,9416126,9416984,9418045,9418201,9418401,9418777,9420309,9420452,9422432,9422596,9423038,9423662,9424213&ipbits=0&mt=1450796650&key=yt6&upn=H0oyL2-re48&expire=1450818317&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&ms=au&mv=m&source=youtube&pl=24&dur=315.512&lmt=1399587588314656&ip=213.162.159.78&id=o-AMAwqR0HAnUxjM_qnn99H9-pPrAZMT_la4pbsUPB2Mty&mn=sn-5hne6n7e&mm=31&sver=3&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&requiressl=yes&signature=347047F08F4D90C5C055AD8E48E1044AAD8AF985777.B44CD80B87D8F588F132F5E89150D3739EEE74C94C9 -> failed 16:05:19 T:140520737499072 DEBUG: Loading settings for https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?ratebypass=yes&itag=22&mime=video/mp4&initcwndbps=438750&fexp=9410705,9416126,9416984,9418045,9418201,9418401,9418777,9420309,9420452,9422432,9422596,9423038,9423662,9424213&ipbits=0&mt=1450796650&key=yt6&upn=H0oyL2-re48&expire=1450818317&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&ms=au&mv=m&source=youtube&pl=24&dur=315.512&lmt=1399587588314656&ip=213.162.159.78&id=o-AMAwqR0HAnUxjM_qnn99H9-pPrAZMT_la4pbsUPB2Mty&mn=sn-5hne6n7e&mm=31&sver=3&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&requiressl=yes&signature=347047F08F4D90C5C055AD8E48E1044AAD8AF985777.B44CD80B87D8F588F132F5E89150D3739EEE74C94C9 16:05:19 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers(https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?ratebypass=yes&itag=22&mime=video/mp4&initcwndbps=438750&fexp=9410705,9416126,9416984,9418045,9418201,9418401,9418777,9420309,9420452,9422432,9422596,9423038,9423662,9424213&ipbits=0&mt=1450796650&key=yt6&upn=H0oyL2-re48&expire=1450818317&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&ms=au&mv=m&source=youtube&pl=24&dur=315.512&lmt=1399587588314656&ip=213.162.159.78&id=o-AMAwqR0HAnUxjM_qnn99H9-pPrAZMT_la4pbsUPB2Mty&mn=sn-5hne6n7e&mm=31&sver=3&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&requiressl=yes&signature=347047F08F4D90C5C055AD8E48E1044AAD8AF985777.B44CD80B87D8F588F132F5E89150D3739EEE74C94C9) 16:05:19 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 16:05:19 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 16:05:19 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/mms/udp 16:05:19 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 16:05:19 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 16:05:19 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 16:05:19 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 16:05:19 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams 16:05:19 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp 16:05:19 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2 16:05:19 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 16:05:19 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 16:05:19 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 16:05:19 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 16:05:19 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 16:05:19 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 16:05:19 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1) 16:05:19 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 16:05:19 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 16:05:19 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1) 16:05:19 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 16:05:19 T:140520737499072 NOTICE: DVDPlayer: Opening: https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?ratebypass=yes&itag=22&mime=video/mp4&initcwndbps=438750&fexp=9410705,9416126,9416984,9418045,9418201,9418401,9418777,9420309,9420452,9422432,9422596,9423038,9423662,9424213&ipbits=0&mt=1450796650&key=yt6&upn=H0oyL2-re48&expire=1450818317&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&ms=au&mv=m&source=youtube&pl=24&dur=315.512&lmt=1399587588314656&ip=213.162.159.78&id=o-AMAwqR0HAnUxjM_qnn99H9-pPrAZMT_la4pbsUPB2Mty&mn=sn-5hne6n7e&mm=31&sver=3&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&requiressl=yes&signature=347047F08F4D90C5C055AD8E48E1044AAD8AF985777.B44CD80B87D8F588F132F5E89150D3739EEE74C94C9 16:05:19 T:140520737499072 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 16:05:19 T:140520737499072 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec 16:05:19 T:140520737499072 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:05:19 T:140520737499072 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 16:05:19 T:140519778805504 NOTICE: Thread DVDPlayer start, auto delete: false 16:05:19 T:140519778805504 NOTICE: Creating InputStream 16:05:20 T:140520737499072 DEBUG: ------ Window Init (DialogBusy.xml) ------ 16:05:20 T:140519778805504 ERROR: CCurlFile::Stat - Failed: HTTP response code said error(22) for https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?ratebypass=yes&itag=22&mime=video/mp4&initcwndbps=438750&fexp=9410705,9416126,9416984,9418045,9418201,9418401,9418777,9420309,9420452,9422432,9422596,9423038,9423662,9424213&ipbits=0&mt=1450796650&key=yt6&upn=H0oyL2-re48&expire=1450818317&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&ms=au&mv=m&source=youtube&pl=24&dur=315.512&lmt=1399587588314656&ip=213.162.159.78&id=o-AMAwqR0HAnUxjM_qnn99H9-pPrAZMT_la4pbsUPB2Mty&mn=sn-5hne6n7e&mm=31&sver=3&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&requiressl=yes&signature=347047F08F4D90C5C055AD8E48E1044AAD8AF985777.B44CD80B87D8F588F132F5E89150D3739EEE74C94C9 16:05:20 T:140519778805504 DEBUG: CCurlFile::GetMimeType - https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?ratebypass=yes&itag=22&mime=video/mp4&initcwndbps=438750&fexp=9410705,9416126,9416984,9418045,9418201,9418401,9418777,9420309,9420452,9422432,9422596,9423038,9423662,9424213&ipbits=0&mt=1450796650&key=yt6&upn=H0oyL2-re48&expire=1450818317&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&ms=au&mv=m&source=youtube&pl=24&dur=315.512&lmt=1399587588314656&ip=213.162.159.78&id=o-AMAwqR0HAnUxjM_qnn99H9-pPrAZMT_la4pbsUPB2Mty&mn=sn-5hne6n7e&mm=31&sver=3&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&requiressl=yes&signature=347047F08F4D90C5C055AD8E48E1044AAD8AF985777.B44CD80B87D8F588F132F5E89150D3739EEE74C94C9 -> failed 16:05:20 T:140519778805504 DEBUG: CFileCache::Open - opening using cache 16:05:20 T:140519778805504 DEBUG: CurlFile::Open(0x7fccec1bf050) https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?ratebypass=yes&itag=22&mime=video/mp4&initcwndbps=438750&fexp=9410705,9416126,9416984,9418045,9418201,9418401,9418777,9420309,9420452,9422432,9422596,9423038,9423662,9424213&ipbits=0&mt=1450796650&key=yt6&upn=H0oyL2-re48&expire=1450818317&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&ms=au&mv=m&source=youtube&pl=24&dur=315.512&lmt=1399587588314656&ip=213.162.159.78&id=o-AMAwqR0HAnUxjM_qnn99H9-pPrAZMT_la4pbsUPB2Mty&mn=sn-5hne6n7e&mm=31&sver=3&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&requiressl=yes&signature=347047F08F4D90C5C055AD8E48E1044AAD8AF985777.B44CD80B87D8F588F132F5E89150D3739EEE74C94C9 16:05:20 T:140520737499072 DEBUG: ------ Window Deinit (DialogProgress.xml) ------ 16:05:20 T:140519778805504 ERROR: CCurlFile::FillBuffer - Failed: HTTP returned error 403 16:05:20 T:140519778805504 ERROR: CCurlFile::Open failed with code 403 for https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?ratebypass=yes&itag=22&mime=video/mp4&initcwndbps=438750&fexp=9410705,9416126,9416984,9418045,9418201,9418401,9418777,9420309,9420452,9422432,9422596,9423038,9423662,9424213&ipbits=0&mt=1450796650&key=yt6&upn=H0oyL2-re48&expire=1450818317&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&ms=au&mv=m&source=youtube&pl=24&dur=315.512&lmt=1399587588314656&ip=213.162.159.78&id=o-AMAwqR0HAnUxjM_qnn99H9-pPrAZMT_la4pbsUPB2Mty&mn=sn-5hne6n7e&mm=31&sver=3&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&requiressl=yes&signature=347047F08F4D90C5C055AD8E48E1044AAD8AF985777.B44CD80B87D8F588F132F5E89150D3739EEE74C94C9 16:05:20 T:140519778805504 ERROR: Open - failed to open source 16:05:20 T:140519778805504 ERROR: CDVDPlayer::OpenInputStream - error opening [https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?ratebypass=yes&itag=22&mime=video/mp4&initcwndbps=438750&fexp=9410705,9416126,9416984,9418045,9418201,9418401,9418777,9420309,9420452,9422432,9422596,9423038,9423662,9424213&ipbits=0&mt=1450796650&key=yt6&upn=H0oyL2-re48&expire=1450818317&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&ms=au&mv=m&source=youtube&pl=24&dur=315.512&lmt=1399587588314656&ip=213.162.159.78&id=o-AMAwqR0HAnUxjM_qnn99H9-pPrAZMT_la4pbsUPB2Mty&mn=sn-5hne6n7e&mm=31&sver=3&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&requiressl=yes&signature=347047F08F4D90C5C055AD8E48E1044AAD8AF985777.B44CD80B87D8F588F132F5E89150D3739EEE74C94C9] 16:05:20 T:140519778805504 NOTICE: CDVDPlayer::OnExit() 16:05:20 T:140519778805504 DEBUG: OnPlayBackStopped: play state was 1, starting 1 16:05:20 T:140519778805504 DEBUG: Thread DVDPlayer 140519778805504 terminating 16:05:20 T:140520737499072 DEBUG: OnPlayBackStopped: play state was 3, starting 0 16:05:20 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 16:05:20 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 16:05:20 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Player, sender=xbmc, message=OnStop) 16:05:20 T:140520737499072 ERROR: Playlist Player: skipping unplayable item: 0, path [plugin://plugin.video.youtube/play/?video_id=Qtb11P1FWnc] 16:05:20 T:140520737499072 DEBUG: Playlist Player: no more playable items... aborting playback 16:05:20 T:140519778805504 NOTICE: Thread BackgroundLoader start, auto delete: false 16:05:20 T:140519778805504 DEBUG: Thread BackgroundLoader 140519778805504 terminating 16:05:20 T:140520737499072 NOTICE: CDVDPlayer::CloseFile() 16:05:20 T:140520737499072 NOTICE: DVDPlayer: waiting for threads to exit 16:05:20 T:140520737499072 NOTICE: DVDPlayer: finished waiting 16:05:20 T:140520737499072 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:05:20 T:140520737499072 NOTICE: CDVDPlayer::CloseFile() 16:05:20 T:140520737499072 NOTICE: DVDPlayer: waiting for threads to exit 16:05:20 T:140520737499072 NOTICE: DVDPlayer: finished waiting 16:05:20 T:140520737499072 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:05:20 T:140519166433024 DEBUG: DoWork - Saving file state for video item plugin://plugin.video.youtube/play/?video_id=Qtb11P1FWnc 16:05:20 T:140520737499072 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 16:05:24 T:140520737499072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:05:24 T:140520737499072 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:05:24 T:140520737499072 DEBUG: OnPlayMedia plugin://plugin.video.youtube/play/?video_id=Qtb11P1FWnc 16:05:24 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 16:05:24 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 16:05:24 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Playlist, sender=xbmc, message=OnClear) 16:05:24 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 16:05:24 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 16:05:24 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Playlist, sender=xbmc, message=OnAdd) 16:05:24 T:140520737499072 DEBUG: StartScript - calling plugin YouTube('plugin://plugin.video.youtube/play/','14','?video_id=Qtb11P1FWnc') 16:05:24 T:140520737499072 DEBUG: WaitOnScriptResult - waiting on the YouTube (id=28) plugin... 16:05:24 T:140519778805504 NOTICE: Thread LanguageInvoker start, auto delete: false 16:05:24 T:140519778805504 INFO: initializing python engine. 16:05:24 T:140519778805504 DEBUG: CPythonInvoker(28, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): start processing 16:05:24 T:140519778805504 NOTICE: -->Python Interpreter Initialized<-- 16:05:24 T:140519778805504 DEBUG: CPythonInvoker(28, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): the source file to load is "/home/ruschi/.kodi/addons/plugin.video.youtube/default.py" 16:05:24 T:140519778805504 DEBUG: CPythonInvoker(28, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): setting the Python path to /home/ruschi/.kodi/addons/plugin.video.youtube:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0 16:05:24 T:140519778805504 DEBUG: CPythonInvoker(28, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): entering source directory /home/ruschi/.kodi/addons/plugin.video.youtube 16:05:24 T:140519778805504 DEBUG: CPythonInvoker(28, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): instantiating addon using automatically obtained id of "plugin.video.youtube" dependent on version 2.1.0 of the xbmc.python api 16:05:24 T:140519778805504 DEBUG: [plugin.video.youtube] Starting Kodion framework by bromix... 16:05:24 T:140519778805504 NOTICE: [plugin.video.youtube] Running: YouTube (5.1.13) on Isengard (15.2) with Python 2.7.6 16:05:24 T:140519778805504 DEBUG: [plugin.video.youtube] Path: "/play/ 16:05:24 T:140519778805504 DEBUG: [plugin.video.youtube] Params: "{'video_id': u'Qtb11P1FWnc'}" 16:05:24 T:140519778805504 DEBUG: [plugin.video.youtube] Selecting YouTube config "Isengard" 16:05:24 T:140519778805504 DEBUG: [plugin.video.youtube] User is not logged in 16:05:26 T:140520737499072 DEBUG: DialogProgress::StartModal called 16:05:26 T:140520737499072 DEBUG: ------ Window Init (DialogProgress.xml) ------ 16:05:26 T:140519778805504 DEBUG: [plugin.video.youtube] selectable streams: 6 16:05:26 T:140519778805504 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [720, 0], 'video': {'encoding': 'h.264', 'height': 720}, 'title': '[B]720p[/B] (mp4;h.264 / aac@192)', 'url': 'https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?pl=24&itag=22&upn=jqBT4MEv5jQ&source=youtube&mn=sn-5hne6n7e&mm=31&id=o-AKIArzae5BKcJLLHR51vlQ5Doghwdg5kkU-Thv30Gipc&ip=213.162.159.78&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&mv=m&mt=1450796650&ms=au&fexp=9407168,9416008,9416075,9416126,9418204,9418400,9418404,9420452,9421982,9422596,9423060,9423662,9424214&ratebypass=yes&requiressl=yes&initcwndbps=438750&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&key=yt6&mime=video/mp4&dur=315.512&ipbits=0&sver=3&expire=1450818325&lmt=1399587588314656&signature=44444468B0EEC4297156C2E741DAB785EE7C0EC7747.6873CEE8D1BCA317D137B1F532E8732E6164F316316', 'container': 'mp4', 'audio': {'bitrate': 192, 'encoding': 'aac'}} 16:05:26 T:140519778805504 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [360, 0], 'video': {'encoding': 'h.264', 'height': 360}, 'title': '[B]360p[/B] (mp4;h.264 / aac@96)', 'url': 'https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?pl=24&itag=18&upn=jqBT4MEv5jQ&source=youtube&mn=sn-5hne6n7e&mm=31&id=o-AKIArzae5BKcJLLHR51vlQ5Doghwdg5kkU-Thv30Gipc&ip=213.162.159.78&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&mv=m&mt=1450796650&ms=au&fexp=9407168,9416008,9416075,9416126,9418204,9418400,9418404,9420452,9421982,9422596,9423060,9423662,9424214&ratebypass=yes&requiressl=yes&initcwndbps=438750&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&key=yt6&mime=video/mp4&dur=315.512&ipbits=0&sver=3&expire=1450818325&lmt=1399587556254155&signature=B73473E2010F4476C3BE21F6FF01D1F8E5CBF74A3ED.8F128D6ADA53C3A82F007FF1CEB18F4F74A4200F00F', 'container': 'mp4', 'audio': {'bitrate': 96, 'encoding': 'aac'}} 16:05:26 T:140519778805504 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [360, -1], 'video': {'encoding': 'vp8', 'height': 360}, 'title': '[B]360p[/B] (webm;vp8 / vorbis@128)', 'url': 'https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?pl=24&itag=43&upn=jqBT4MEv5jQ&source=youtube&mn=sn-5hne6n7e&mm=31&id=o-AKIArzae5BKcJLLHR51vlQ5Doghwdg5kkU-Thv30Gipc&ip=213.162.159.78&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&mv=m&mt=1450796650&ms=au&fexp=9407168,9416008,9416075,9416126,9418204,9418400,9418404,9420452,9421982,9422596,9423060,9423662,9424214&ratebypass=yes&requiressl=yes&initcwndbps=438750&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&key=yt6&mime=video/webm&dur=0.000&ipbits=0&sver=3&expire=1450818325&lmt=1396861852920802&signature=8C3FC311EE95EFAD8885ED69A0A17046864B725DE23.24054736349DB2F602ED2B68E3AEC7C5A3CAFEA8EA8', 'container': 'webm', 'audio': {'bitrate': 128, 'encoding': 'vorbis'}} 16:05:26 T:140519778805504 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [240, 0], 'video': {'encoding': 'h.263', 'height': 240}, 'title': '[B]240p[/B] (flv;h.263 / mp3@64)', 'url': 'https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?pl=24&itag=5&upn=jqBT4MEv5jQ&source=youtube&mn=sn-5hne6n7e&mm=31&id=o-AKIArzae5BKcJLLHR51vlQ5Doghwdg5kkU-Thv30Gipc&ip=213.162.159.78&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,requiressl,source,upn,expire&mv=m&mt=1450796650&ms=au&fexp=9407168,9416008,9416075,9416126,9418204,9418400,9418404,9420452,9421982,9422596,9423060,9423662,9424214&requiressl=yes&initcwndbps=438750&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&key=yt6&mime=video/x-flv&dur=315.481&ipbits=0&sver=3&expire=1450818325&lmt=1396861351135557&signature=DFACFAD072D7ACD61EC1AF4995FD41CA262E7F58179.8B0EB6D5405DF68A7729ED772B21B3D88A56C414414', 'container': 'flv', 'audio': {'bitrate': 64, 'encoding': 'mp3'}} 16:05:26 T:140519778805504 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [240, -20], 'video': {'encoding': 'mpeg-4', 'height': 240}, 'title': '[B]240p[/B] (3gp;mpeg-4 / aac@32)', 'url': 'https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?pl=24&itag=36&upn=jqBT4MEv5jQ&source=youtube&mn=sn-5hne6n7e&mm=31&id=o-AKIArzae5BKcJLLHR51vlQ5Doghwdg5kkU-Thv30Gipc&ip=213.162.159.78&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,requiressl,source,upn,expire&mv=m&mt=1450796650&ms=au&fexp=9407168,9416008,9416075,9416126,9418204,9418400,9418404,9420452,9421982,9422596,9423060,9423662,9424214&requiressl=yes&initcwndbps=438750&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&key=yt6&mime=video/3gpp&dur=315.652&ipbits=0&sver=3&expire=1450818325&lmt=1396861407423213&signature=037637B61D7A66B99EDB4288C97ECCF5482AADA6022.537BCA4D2AFDEE925BA6F666FBE9AA6BF5E9F50F50F', 'container': '3gp', 'audio': {'bitrate': 32, 'encoding': 'aac'}} 16:05:26 T:140519778805504 DEBUG: [plugin.video.youtube] selectable stream: {'sort': [144, -20], 'video': {'encoding': 'mpeg-4', 'height': 144}, 'title': '[B]144p[/B] (3gp;mpeg-4 / aac@24)', 'url': 'https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?pl=24&itag=17&upn=jqBT4MEv5jQ&source=youtube&mn=sn-5hne6n7e&mm=31&id=o-AKIArzae5BKcJLLHR51vlQ5Doghwdg5kkU-Thv30Gipc&ip=213.162.159.78&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,requiressl,source,upn,expire&mv=m&mt=1450796650&ms=au&fexp=9407168,9416008,9416075,9416126,9418204,9418400,9418404,9420452,9421982,9422596,9423060,9423662,9424214&requiressl=yes&initcwndbps=438750&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&key=yt6&mime=video/3gpp&dur=315.605&ipbits=0&sver=3&expire=1450818325&lmt=1396861414005259&signature=D22422C412F6F464E023085D47668E0E95A5221DCA9.12866192DD57A1447647DA0F7A0C896AD362EEFDEFD"', 'container': '3gp', 'audio': {'bitrate': 24, 'encoding': 'aac'}} 16:05:26 T:140519778805504 DEBUG: [plugin.video.youtube] selected stream: {'sort': [720, 0], 'video': {'encoding': 'h.264', 'height': 720}, 'title': '[B]720p[/B] (mp4;h.264 / aac@192)', 'url': 'https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?pl=24&itag=22&upn=jqBT4MEv5jQ&source=youtube&mn=sn-5hne6n7e&mm=31&id=o-AKIArzae5BKcJLLHR51vlQ5Doghwdg5kkU-Thv30Gipc&ip=213.162.159.78&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&mv=m&mt=1450796650&ms=au&fexp=9407168,9416008,9416075,9416126,9418204,9418400,9418404,9420452,9421982,9422596,9423060,9423662,9424214&ratebypass=yes&requiressl=yes&initcwndbps=438750&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&key=yt6&mime=video/mp4&dur=315.512&ipbits=0&sver=3&expire=1450818325&lmt=1399587588314656&signature=44444468B0EEC4297156C2E741DAB785EE7C0EC7747.6873CEE8D1BCA317D137B1F532E8732E6164F316316', 'container': 'mp4', 'audio': {'bitrate': 192, 'encoding': 'aac'}} 16:05:26 T:140519778805504 DEBUG: [plugin.video.youtube] Selecting YouTube config "Isengard" 16:05:26 T:140519778805504 DEBUG: [plugin.video.youtube] User is not logged in 16:05:26 T:140519778805504 DEBUG: [plugin.video.youtube] No data for video 'Qtb11P1FWnc' cached 16:05:27 T:140519778805504 DEBUG: POParser: loaded 100 strings from file /home/ruschi/.kodi/addons/plugin.video.youtube/resources/language/German/strings.po 16:05:27 T:140519778805504 DEBUG: POParser: loaded 0 strings from file /home/ruschi/.kodi/addons/plugin.video.youtube/resources/language/English/strings.po 16:05:27 T:140519778805504 DEBUG: [plugin.video.youtube] Converting VideoItem 16:05:27 T:140520737499072 DEBUG: WaitOnScriptResult- plugin returned successfully 16:05:27 T:140519778805504 DEBUG: [plugin.video.youtube] Shutdown of Kodion 16:05:27 T:140519778805504 INFO: CPythonInvoker(28, /home/ruschi/.kodi/addons/plugin.video.youtube/default.py): script successfully run 16:05:27 T:140519778805504 INFO: Python script stopped 16:05:27 T:140519778805504 DEBUG: Thread LanguageInvoker 140519778805504 terminating 16:05:27 T:140520737499072 ERROR: CCurlFile::Stat - Failed: HTTP response code said error(22) for https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?pl=24&itag=22&upn=jqBT4MEv5jQ&source=youtube&mn=sn-5hne6n7e&mm=31&id=o-AKIArzae5BKcJLLHR51vlQ5Doghwdg5kkU-Thv30Gipc&ip=213.162.159.78&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&mv=m&mt=1450796650&ms=au&fexp=9407168,9416008,9416075,9416126,9418204,9418400,9418404,9420452,9421982,9422596,9423060,9423662,9424214&ratebypass=yes&requiressl=yes&initcwndbps=438750&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&key=yt6&mime=video/mp4&dur=315.512&ipbits=0&sver=3&expire=1450818325&lmt=1399587588314656&signature=44444468B0EEC4297156C2E741DAB785EE7C0EC7747.6873CEE8D1BCA317D137B1F532E8732E6164F316316 16:05:27 T:140520737499072 DEBUG: CCurlFile::GetMimeType - https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?pl=24&itag=22&upn=jqBT4MEv5jQ&source=youtube&mn=sn-5hne6n7e&mm=31&id=o-AKIArzae5BKcJLLHR51vlQ5Doghwdg5kkU-Thv30Gipc&ip=213.162.159.78&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&mv=m&mt=1450796650&ms=au&fexp=9407168,9416008,9416075,9416126,9418204,9418400,9418404,9420452,9421982,9422596,9423060,9423662,9424214&ratebypass=yes&requiressl=yes&initcwndbps=438750&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&key=yt6&mime=video/mp4&dur=315.512&ipbits=0&sver=3&expire=1450818325&lmt=1399587588314656&signature=44444468B0EEC4297156C2E741DAB785EE7C0EC7747.6873CEE8D1BCA317D137B1F532E8732E6164F316316 -> failed 16:05:27 T:140520737499072 DEBUG: Loading settings for https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?pl=24&itag=22&upn=jqBT4MEv5jQ&source=youtube&mn=sn-5hne6n7e&mm=31&id=o-AKIArzae5BKcJLLHR51vlQ5Doghwdg5kkU-Thv30Gipc&ip=213.162.159.78&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&mv=m&mt=1450796650&ms=au&fexp=9407168,9416008,9416075,9416126,9418204,9418400,9418404,9420452,9421982,9422596,9423060,9423662,9424214&ratebypass=yes&requiressl=yes&initcwndbps=438750&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&key=yt6&mime=video/mp4&dur=315.512&ipbits=0&sver=3&expire=1450818325&lmt=1399587588314656&signature=44444468B0EEC4297156C2E741DAB785EE7C0EC7747.6873CEE8D1BCA317D137B1F532E8732E6164F316316 16:05:27 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers(https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?pl=24&itag=22&upn=jqBT4MEv5jQ&source=youtube&mn=sn-5hne6n7e&mm=31&id=o-AKIArzae5BKcJLLHR51vlQ5Doghwdg5kkU-Thv30Gipc&ip=213.162.159.78&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&mv=m&mt=1450796650&ms=au&fexp=9407168,9416008,9416075,9416126,9418204,9418400,9418404,9420452,9421982,9422596,9423060,9423662,9424214&ratebypass=yes&requiressl=yes&initcwndbps=438750&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&key=yt6&mime=video/mp4&dur=315.512&ipbits=0&sver=3&expire=1450818325&lmt=1399587588314656&signature=44444468B0EEC4297156C2E741DAB785EE7C0EC7747.6873CEE8D1BCA317D137B1F532E8732E6164F316316) 16:05:27 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 16:05:27 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 16:05:27 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/mms/udp 16:05:27 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 16:05:27 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 16:05:27 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 16:05:27 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 16:05:27 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams 16:05:27 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp 16:05:27 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2 16:05:27 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 16:05:27 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 16:05:27 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 16:05:27 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 16:05:27 T:140520737499072 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 16:05:27 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 16:05:27 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1) 16:05:27 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 16:05:27 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 16:05:27 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1) 16:05:27 T:140520737499072 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 16:05:27 T:140520737499072 NOTICE: DVDPlayer: Opening: https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?pl=24&itag=22&upn=jqBT4MEv5jQ&source=youtube&mn=sn-5hne6n7e&mm=31&id=o-AKIArzae5BKcJLLHR51vlQ5Doghwdg5kkU-Thv30Gipc&ip=213.162.159.78&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&mv=m&mt=1450796650&ms=au&fexp=9407168,9416008,9416075,9416126,9418204,9418400,9418404,9420452,9421982,9422596,9423060,9423662,9424214&ratebypass=yes&requiressl=yes&initcwndbps=438750&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&key=yt6&mime=video/mp4&dur=315.512&ipbits=0&sver=3&expire=1450818325&lmt=1399587588314656&signature=44444468B0EEC4297156C2E741DAB785EE7C0EC7747.6873CEE8D1BCA317D137B1F532E8732E6164F316316 16:05:27 T:140520737499072 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 16:05:27 T:140520737499072 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec 16:05:27 T:140520737499072 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:05:27 T:140520737499072 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 16:05:27 T:140519778805504 NOTICE: Thread DVDPlayer start, auto delete: false 16:05:27 T:140519778805504 NOTICE: Creating InputStream 16:05:28 T:140520737499072 DEBUG: ------ Window Init (DialogBusy.xml) ------ 16:05:28 T:140519778805504 ERROR: CCurlFile::Stat - Failed: HTTP response code said error(22) for https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?pl=24&itag=22&upn=jqBT4MEv5jQ&source=youtube&mn=sn-5hne6n7e&mm=31&id=o-AKIArzae5BKcJLLHR51vlQ5Doghwdg5kkU-Thv30Gipc&ip=213.162.159.78&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&mv=m&mt=1450796650&ms=au&fexp=9407168,9416008,9416075,9416126,9418204,9418400,9418404,9420452,9421982,9422596,9423060,9423662,9424214&ratebypass=yes&requiressl=yes&initcwndbps=438750&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&key=yt6&mime=video/mp4&dur=315.512&ipbits=0&sver=3&expire=1450818325&lmt=1399587588314656&signature=44444468B0EEC4297156C2E741DAB785EE7C0EC7747.6873CEE8D1BCA317D137B1F532E8732E6164F316316 16:05:28 T:140519778805504 DEBUG: CCurlFile::GetMimeType - https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?pl=24&itag=22&upn=jqBT4MEv5jQ&source=youtube&mn=sn-5hne6n7e&mm=31&id=o-AKIArzae5BKcJLLHR51vlQ5Doghwdg5kkU-Thv30Gipc&ip=213.162.159.78&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&mv=m&mt=1450796650&ms=au&fexp=9407168,9416008,9416075,9416126,9418204,9418400,9418404,9420452,9421982,9422596,9423060,9423662,9424214&ratebypass=yes&requiressl=yes&initcwndbps=438750&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&key=yt6&mime=video/mp4&dur=315.512&ipbits=0&sver=3&expire=1450818325&lmt=1399587588314656&signature=44444468B0EEC4297156C2E741DAB785EE7C0EC7747.6873CEE8D1BCA317D137B1F532E8732E6164F316316 -> failed 16:05:28 T:140519778805504 DEBUG: CFileCache::Open - opening using cache 16:05:28 T:140519778805504 DEBUG: CurlFile::Open(0x7fccec2e3880) https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?pl=24&itag=22&upn=jqBT4MEv5jQ&source=youtube&mn=sn-5hne6n7e&mm=31&id=o-AKIArzae5BKcJLLHR51vlQ5Doghwdg5kkU-Thv30Gipc&ip=213.162.159.78&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&mv=m&mt=1450796650&ms=au&fexp=9407168,9416008,9416075,9416126,9418204,9418400,9418404,9420452,9421982,9422596,9423060,9423662,9424214&ratebypass=yes&requiressl=yes&initcwndbps=438750&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&key=yt6&mime=video/mp4&dur=315.512&ipbits=0&sver=3&expire=1450818325&lmt=1399587588314656&signature=44444468B0EEC4297156C2E741DAB785EE7C0EC7747.6873CEE8D1BCA317D137B1F532E8732E6164F316316 16:05:28 T:140520737499072 DEBUG: ------ Window Deinit (DialogProgress.xml) ------ 16:05:28 T:140519778805504 ERROR: CCurlFile::FillBuffer - Failed: HTTP returned error 403 16:05:28 T:140519778805504 ERROR: CCurlFile::Open failed with code 403 for https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?pl=24&itag=22&upn=jqBT4MEv5jQ&source=youtube&mn=sn-5hne6n7e&mm=31&id=o-AKIArzae5BKcJLLHR51vlQ5Doghwdg5kkU-Thv30Gipc&ip=213.162.159.78&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&mv=m&mt=1450796650&ms=au&fexp=9407168,9416008,9416075,9416126,9418204,9418400,9418404,9420452,9421982,9422596,9423060,9423662,9424214&ratebypass=yes&requiressl=yes&initcwndbps=438750&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&key=yt6&mime=video/mp4&dur=315.512&ipbits=0&sver=3&expire=1450818325&lmt=1399587588314656&signature=44444468B0EEC4297156C2E741DAB785EE7C0EC7747.6873CEE8D1BCA317D137B1F532E8732E6164F316316 16:05:28 T:140519778805504 ERROR: Open - failed to open source 16:05:28 T:140519778805504 ERROR: CDVDPlayer::OpenInputStream - error opening [https://r4---sn-5hne6n7e.googlevideo.com/videoplayback?pl=24&itag=22&upn=jqBT4MEv5jQ&source=youtube&mn=sn-5hne6n7e&mm=31&id=o-AKIArzae5BKcJLLHR51vlQ5Doghwdg5kkU-Thv30Gipc&ip=213.162.159.78&sparams=dur,id,initcwndbps,ip,ipbits,itag,lmt,mime,mm,mn,ms,mv,nh,pl,ratebypass,requiressl,source,upn,expire&mv=m&mt=1450796650&ms=au&fexp=9407168,9416008,9416075,9416126,9418204,9418400,9418404,9420452,9421982,9422596,9423060,9423662,9424214&ratebypass=yes&requiressl=yes&initcwndbps=438750&nh=IgpwcjAxLmFtczE1KgkxMjcuMC4wLjE&key=yt6&mime=video/mp4&dur=315.512&ipbits=0&sver=3&expire=1450818325&lmt=1399587588314656&signature=44444468B0EEC4297156C2E741DAB785EE7C0EC7747.6873CEE8D1BCA317D137B1F532E8732E6164F316316] 16:05:28 T:140519778805504 NOTICE: CDVDPlayer::OnExit() 16:05:28 T:140519778805504 DEBUG: OnPlayBackStopped: play state was 1, starting 1 16:05:28 T:140519778805504 DEBUG: Thread DVDPlayer 140519778805504 terminating 16:05:28 T:140520737499072 DEBUG: OnPlayBackStopped: play state was 3, starting 0 16:05:28 T:140520737499072 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 16:05:28 T:140520737499072 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 16:05:28 T:140520737499072 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Player, sender=xbmc, message=OnStop) 16:05:28 T:140520737499072 ERROR: Playlist Player: skipping unplayable item: 0, path [plugin://plugin.video.youtube/play/?video_id=Qtb11P1FWnc] 16:05:28 T:140520737499072 DEBUG: Playlist Player: no more playable items... aborting playback 16:05:28 T:140519778805504 NOTICE: Thread BackgroundLoader start, auto delete: false 16:05:28 T:140520737499072 DEBUG: ------ Window Deinit (DialogKaiToast.xml) ------ 16:05:28 T:140519778805504 DEBUG: Thread BackgroundLoader 140519778805504 terminating 16:05:28 T:140520737499072 NOTICE: CDVDPlayer::CloseFile() 16:05:28 T:140520737499072 NOTICE: DVDPlayer: waiting for threads to exit 16:05:28 T:140520737499072 NOTICE: DVDPlayer: finished waiting 16:05:28 T:140520737499072 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:05:28 T:140520737499072 NOTICE: CDVDPlayer::CloseFile() 16:05:28 T:140520737499072 NOTICE: DVDPlayer: waiting for threads to exit 16:05:28 T:140520737499072 NOTICE: DVDPlayer: finished waiting 16:05:28 T:140520737499072 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:05:28 T:140520737499072 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 16:05:28 T:140518235301632 DEBUG: DoWork - Saving file state for video item plugin://plugin.video.youtube/play/?video_id=Qtb11P1FWnc 16:05:31 T:140520737499072 INFO: CheckIdle - Closing session to https://r1---sn-5hnednez.googlevideo.com (easy=0x910d890, multi=0x7fcce0882010) 16:05:35 T:140520737499072 DEBUG: Keyboard: scancode: 0x09, sym: 0x001b, unicode: 0x001b, modifier: 0x0 16:05:35 T:140520737499072 DEBUG: OnKey: escape (0xf01b) pressed, action is PreviousMenu 16:05:35 T:140520737499072 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:05:36 T:140520737499072 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 16:05:36 T:140520737499072 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:05:36 T:140520737499072 DEBUG: ------ Window Init (Home.xml) ------ 16:05:36 T:140519166433024 INFO: easy_aquire - Created session to http://www.msftncsi.com 16:05:36 T:140517731497728 NOTICE: Thread LanguageInvoker start, auto delete: false 16:05:36 T:140517731497728 INFO: initializing python engine. 16:05:36 T:140517731497728 DEBUG: CPythonInvoker(29, /home/ruschi/.kodi/addons/script.favourites/default.py): start processing 16:05:36 T:140517731497728 NOTICE: -->Python Interpreter Initialized<-- 16:05:36 T:140517731497728 DEBUG: CPythonInvoker(29, /home/ruschi/.kodi/addons/script.favourites/default.py): the source file to load is "/home/ruschi/.kodi/addons/script.favourites/default.py" 16:05:36 T:140517731497728 DEBUG: CPythonInvoker(29, /home/ruschi/.kodi/addons/script.favourites/default.py): setting the Python path to /home/ruschi/.kodi/addons/script.favourites:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0 16:05:36 T:140517731497728 DEBUG: CPythonInvoker(29, /home/ruschi/.kodi/addons/script.favourites/default.py): entering source directory /home/ruschi/.kodi/addons/script.favourites 16:05:36 T:140517731497728 DEBUG: CPythonInvoker(29, /home/ruschi/.kodi/addons/script.favourites/default.py): instantiating addon using automatically obtained id of "script.favourites" dependent on version 2.19.0 of the xbmc.python api 16:05:36 T:140517731497728 DEBUG: script.favourites: script version 6.0.3 started 16:05:36 T:140517731497728 DEBUG: script.favourites: ### params: {} 16:05:36 T:140517731497728 DEBUG: script.favourites: script stopped 16:05:36 T:140517731497728 INFO: CPythonInvoker(29, /home/ruschi/.kodi/addons/script.favourites/default.py): script successfully run 16:05:36 T:140517731497728 INFO: Python script stopped 16:05:36 T:140517731497728 DEBUG: Thread LanguageInvoker 140517731497728 terminating 16:05:37 T:140520737499072 DEBUG: Keyboard: scancode: 0x72, sym: 0x0113, unicode: 0x0000, modifier: 0x0 16:05:37 T:140520737499072 DEBUG: OnKey: right (0xf083) pressed, action is Right 16:05:37 T:140520737499072 DEBUG: Keyboard: scancode: 0x72, sym: 0x0113, unicode: 0x0000, modifier: 0x0 16:05:37 T:140520737499072 DEBUG: OnKey: right (0xf083) pressed, action is Right 16:05:38 T:140520737499072 DEBUG: Keyboard: scancode: 0x72, sym: 0x0113, unicode: 0x0000, modifier: 0x0 16:05:38 T:140520737499072 DEBUG: OnKey: right (0xf083) pressed, action is Right 16:05:38 T:140520737499072 DEBUG: Keyboard: scancode: 0x72, sym: 0x0113, unicode: 0x0000, modifier: 0x0 16:05:38 T:140520737499072 DEBUG: OnKey: right (0xf083) pressed, action is Right 16:05:39 T:140520737499072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:05:39 T:140520737499072 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:05:39 T:140520737499072 DEBUG: Activating window ID: 10004 16:05:39 T:140520737499072 DEBUG: ------ Window Deinit (Home.xml) ------ 16:05:39 T:140520737499072 DEBUG: ------ Window Init (Settings.xml) ------ 16:05:39 T:140520737499072 DEBUG: UPower: Received an unknown signal DeviceChanged 16:05:42 T:140520737499072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:05:42 T:140520737499072 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:05:42 T:140520737499072 DEBUG: Activating window ID: 10016 16:05:42 T:140520737499072 DEBUG: ------ Window Deinit (Settings.xml) ------ 16:05:42 T:140520737499072 DEBUG: ------ Window Init (SettingsCategory.xml) ------ 16:05:43 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:05:43 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:05:43 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:05:43 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:05:44 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:05:44 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:05:45 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:05:45 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:05:45 T:140520737499072 DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0 16:05:45 T:140520737499072 DEBUG: OnKey: down (0xf081) pressed, action is Down 16:05:46 T:140520737499072 DEBUG: Keyboard: scancode: 0x72, sym: 0x0113, unicode: 0x0000, modifier: 0x0 16:05:46 T:140520737499072 DEBUG: OnKey: right (0xf083) pressed, action is Right 16:05:49 T:140520737499072 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0 16:05:49 T:140520737499072 DEBUG: OnKey: return (0xf00d) pressed, action is Select 16:05:49 T:140520737499072 NOTICE: Disabled debug logging due to GUI setting. Level 0. 16:05:49 T:140520737499072 NOTICE: Log level changed to "LOG_LEVEL_NORMAL" 16:05:54 T:140520226088704 ERROR: PulseAudio: Failed to connect context 16:05:54 T:140520226088704 NOTICE: PulseAudio might not be running. Context was not created. 16:05:54 T:140520226088704 NOTICE: Found 1 Lists of Devices 16:05:54 T:140520226088704 NOTICE: Enumerated ALSA devices: 16:05:54 T:140520226088704 NOTICE: Device 1 16:05:54 T:140520226088704 NOTICE: m_deviceName : default 16:05:54 T:140520226088704 NOTICE: m_displayName : Default (HDA NVidia HDMI 1) 16:05:54 T:140520226088704 NOTICE: m_displayNameExtra: 16:05:54 T:140520226088704 NOTICE: m_deviceType : AE_DEVTYPE_PCM 16:05:54 T:140520226088704 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR 16:05:54 T:140520226088704 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 16:05:54 T:140520226088704 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE 16:05:54 T:140520226088704 NOTICE: Device 2 16:05:54 T:140520226088704 NOTICE: m_deviceName : hdmi:CARD=NVidia,DEV=0 16:05:54 T:140520226088704 NOTICE: m_displayName : HDA NVidia 16:05:54 T:140520226088704 NOTICE: m_displayNameExtra: HDMI #0 16:05:54 T:140520226088704 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 16:05:54 T:140520226088704 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR 16:05:54 T:140520226088704 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 16:05:54 T:140520226088704 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_LPCM,AE_FMT_DTSHD,AE_FMT_TRUEHD,AE_FMT_EAC3,AE_FMT_DTS,AE_FMT_AC3,AE_FMT_AAC 16:05:54 T:140520226088704 NOTICE: Device 3 16:05:54 T:140520226088704 NOTICE: m_deviceName : hdmi:CARD=NVidia,DEV=1 16:05:54 T:140520226088704 NOTICE: m_displayName : HDA NVidia 16:05:54 T:140520226088704 NOTICE: m_displayNameExtra: HDMI #1 16:05:54 T:140520226088704 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 16:05:54 T:140520226088704 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR 16:05:54 T:140520226088704 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 16:05:54 T:140520226088704 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_LPCM,AE_FMT_DTSHD,AE_FMT_TRUEHD,AE_FMT_EAC3,AE_FMT_DTS,AE_FMT_AC3,AE_FMT_AAC 16:06:11 T:140520737499072 NOTICE: Samba is idle. Closing the remaining connections