16:33:51 T:140050152400768 NOTICE: special://profile/ is mapped to: special://masterprofile/ 16:33:51 T:140050152400768 NOTICE: ----------------------------------------------------------------------- 16:33:51 T:140050152400768 NOTICE: Starting XBMC (13.0-ALPHA10 Git:27834c6). Platform: x86 Linux 64-bit 16:33:51 T:140050152400768 NOTICE: Using Release XBMC x64 build, compiled Dec 2 2013 by GCC 4.8.1 for x86 Linux 64-bit 3.11.6 16:33:51 T:140050152400768 NOTICE: Running on Linux 64-bit (Ubuntu 13.10, 3.12.7-031207-generic x86_64) 16:33:51 T:140050152400768 NOTICE: Host CPU: Intel(R) Core(TM) i3-3240 CPU @ 3.40GHz, 4 cores available 16:33:51 T:140050152400768 NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc 16:33:51 T:140050152400768 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/xbmc 16:33:51 T:140050152400768 NOTICE: special://masterprofile/ is mapped to: /home/htpc/.xbmc/userdata 16:33:51 T:140050152400768 NOTICE: special://home/ is mapped to: /home/htpc/.xbmc 16:33:51 T:140050152400768 NOTICE: special://temp/ is mapped to: /home/htpc/.xbmc/temp 16:33:51 T:140050152400768 NOTICE: The executable running is: /usr/lib/xbmc/xbmc.bin 16:33:51 T:140050152400768 NOTICE: Local hostname: htpc-MS-7673 16:33:51 T:140050152400768 NOTICE: Log File is located: /home/htpc/.xbmc/temp/xbmc.log 16:33:51 T:140050152400768 NOTICE: ----------------------------------------------------------------------- 16:33:51 T:140050152400768 WARNING: CXRandR::Query - output eDP1 has no current mode, assuming disconnected 16:33:51 T:140050152400768 NOTICE: load settings... 16:33:51 T:140050152400768 NOTICE: CAESinkOSS::EnumerateDevicesEx - No OSS mixer device present: /dev/mixer 16:33:51 T:140050152400768 NOTICE: Found 1 Lists of Devices 16:33:51 T:140050152400768 NOTICE: Enumerated ALSA devices: 16:33:51 T:140050152400768 NOTICE: Device 1 16:33:51 T:140050152400768 NOTICE: m_deviceName : default 16:33:51 T:140050152400768 NOTICE: m_displayName : Playback/recording through the PulseAudio sound server 16:33:51 T:140050152400768 NOTICE: m_displayNameExtra: 16:33:51 T:140050152400768 NOTICE: m_deviceType : AE_DEVTYPE_PCM 16:33:51 T:140050152400768 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR,UNKNOWN1,UNKNOWN2,UNKNOWN3,UNKNOWN4,UNKNOWN5,UNKNOWN6,UNKNOWN7,UNKNOWN8 16:33:51 T:140050152400768 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000 16:33:51 T:140050152400768 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE,AE_FMT_U8 16:33:51 T:140050152400768 NOTICE: Device 2 16:33:51 T:140050152400768 NOTICE: m_deviceName : @:CARD=PCH,DEV=0 16:33:51 T:140050152400768 NOTICE: m_displayName : HDA Intel PCH 16:33:51 T:140050152400768 NOTICE: m_displayNameExtra: ID 892 Analog 16:33:51 T:140050152400768 NOTICE: m_deviceType : AE_DEVTYPE_PCM 16:33:51 T:140050152400768 NOTICE: m_channels : FL,FR 16:33:51 T:140050152400768 NOTICE: m_sampleRates : 48000 16:33:51 T:140050152400768 NOTICE: m_dataFormats : AE_FMT_S32NE 16:33:51 T:140050152400768 NOTICE: Device 3 16:33:51 T:140050152400768 NOTICE: m_deviceName : iec958:CARD=PCH,DEV=0 16:33:51 T:140050152400768 NOTICE: m_displayName : HDA Intel PCH 16:33:51 T:140050152400768 NOTICE: m_displayNameExtra: ID 892 Digital S/PDIF 16:33:51 T:140050152400768 NOTICE: m_deviceType : AE_DEVTYPE_IEC958 16:33:51 T:140050152400768 NOTICE: m_channels : FL,FR 16:33:51 T:140050152400768 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,192000 16:33:51 T:140050152400768 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE 16:33:51 T:140050152400768 NOTICE: Device 4 16:33:51 T:140050152400768 NOTICE: m_deviceName : hdmi:CARD=PCH,DEV=0 16:33:51 T:140050152400768 NOTICE: m_displayName : HDA Intel PCH 16:33:51 T:140050152400768 NOTICE: m_displayNameExtra: HDMI #0 16:33:51 T:140050152400768 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 16:33:51 T:140050152400768 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR 16:33:51 T:140050152400768 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 16:33:51 T:140050152400768 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE 16:33:51 T:140050152400768 NOTICE: Device 5 16:33:51 T:140050152400768 NOTICE: m_deviceName : hdmi:CARD=PCH,DEV=1 16:33:51 T:140050152400768 NOTICE: m_displayName : HDA Intel PCH 16:33:51 T:140050152400768 NOTICE: m_displayNameExtra: ONK TX-SR805 on HDMI #1 16:33:51 T:140050152400768 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 16:33:51 T:140050152400768 NOTICE: m_channels : FL,FR,LFE,FC,BL,BR,SL,SR 16:33:51 T:140050152400768 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 16:33:51 T:140050152400768 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 16:33:51 T:140050152400768 NOTICE: Device 6 16:33:51 T:140050152400768 NOTICE: m_deviceName : hdmi:CARD=PCH,DEV=2 16:33:51 T:140050152400768 NOTICE: m_displayName : HDA Intel PCH 16:33:51 T:140050152400768 NOTICE: m_displayNameExtra: HDMI #2 16:33:51 T:140050152400768 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 16:33:51 T:140050152400768 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR 16:33:51 T:140050152400768 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 16:33:51 T:140050152400768 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE 16:33:51 T:140050152400768 NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml) 16:33:51 T:140050152400768 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml) 16:33:51 T:140050152400768 NOTICE: Default DVD Player: dvdplayer 16:33:51 T:140050152400768 NOTICE: Default Video Player: dvdplayer 16:33:51 T:140050152400768 NOTICE: Default Audio Player: paplayer 16:33:51 T:140050152400768 NOTICE: Disabled debug logging due to GUI setting. Level 0. 16:33:51 T:140050152400768 NOTICE: Log level changed to 0 16:33:51 T:140050152400768 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml 16:33:51 T:140050152400768 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 16:33:51 T:140050152400768 NOTICE: Loaded playercorefactory configuration 16:33:51 T:140050152400768 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml. 16:33:51 T:140050152400768 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping. 16:33:51 T:140050152400768 NOTICE: WakeOnAccess - Load settings : 16:33:51 T:140050152400768 NOTICE: -Network init timeout : [60] sec 16:33:51 T:140050152400768 NOTICE: -Network settle time : [500] ms 16:33:51 T:140050152400768 NOTICE: Registering wakeup entry: 16:33:51 T:140050152400768 NOTICE: HostName : 192.168.100.16 16:33:51 T:140050152400768 NOTICE: MacAddress : 1C:6F:65:27:C1:B6 16:33:51 T:140050152400768 NOTICE: PingPort : 3306 16:33:51 T:140050152400768 NOTICE: PingMode : 1 16:33:51 T:140050152400768 NOTICE: Timeout : 600 (sec) 16:33:51 T:140050152400768 NOTICE: WaitForOnline : 1 (sec) 16:33:51 T:140050152400768 NOTICE: WaitForOnlineEx : 2 (sec) 16:33:51 T:140050152400768 NOTICE: WaitForServices : 0 (sec) 16:33:51 T:140050152400768 NOTICE: Registering wakeup entry: 16:33:51 T:140050152400768 NOTICE: HostName : 192.168.100.18 16:33:51 T:140050152400768 NOTICE: MacAddress : 94:DE:80:06:0B:AC 16:33:51 T:140050152400768 NOTICE: PingPort : 3306 16:33:51 T:140050152400768 NOTICE: PingMode : 1 16:33:51 T:140050152400768 NOTICE: Timeout : 600 (sec) 16:33:51 T:140050152400768 NOTICE: WaitForOnline : 45 (sec) 16:33:51 T:140050152400768 NOTICE: WaitForOnlineEx : 60 (sec) 16:33:51 T:140050152400768 NOTICE: WaitForServices : 0 (sec) 16:33:51 T:140050152400768 NOTICE: Registering wakeup entry: 16:33:51 T:140050152400768 NOTICE: HostName : IPFIRE 16:33:51 T:140050152400768 NOTICE: MacAddress : F4:6D:04:90:99:58 16:33:51 T:140050152400768 NOTICE: PingPort : 0 16:33:51 T:140050152400768 NOTICE: PingMode : 0 16:33:51 T:140050152400768 NOTICE: Timeout : 300 (sec) 16:33:51 T:140050152400768 NOTICE: WaitForOnline : 40 (sec) 16:33:51 T:140050152400768 NOTICE: WaitForOnlineEx : 40 (sec) 16:33:51 T:140050152400768 NOTICE: WaitForServices : 5 (sec) 16:33:51 T:140049822430976 NOTICE: Thread ActiveAE start, auto delete: false 16:33:51 T:140049853400832 NOTICE: Thread AESink start, auto delete: false 16:33:51 T:140050152400768 NOTICE: Running database version Addons15 16:33:51 T:140050152400768 NOTICE: ADDONS: Using repository repository.xbmc.org 16:33:51 T:140050152400768 NOTICE: ADDONS: Using repository repository.xvdr.linux64 16:33:51 T:140049833613056 NOTICE: Thread PeripBusCEC start, auto delete: false 16:33:51 T:140049814038272 NOTICE: Thread PeripBusUSBUdev start, auto delete: false 16:33:51 T:140050152400768 NOTICE: Setup SDL 16:33:51 T:140050152400768 NOTICE: Checking resolution 16 16:33:51 T:140050152400768 NOTICE: Using visual 0x21 16:33:51 T:140050152400768 NOTICE: GL_VENDOR = Intel Open Source Technology Center 16:33:51 T:140050152400768 NOTICE: GL_RENDERER = Mesa DRI Intel(R) Ivybridge Desktop 16:33:51 T:140050152400768 NOTICE: GL_VERSION = 3.0 Mesa 9.2.1 16:33:51 T:140050152400768 NOTICE: GL_SHADING_LANGUAGE_VERSION = 1.30 16:33:51 T:140050152400768 NOTICE: GL_EXTENSIONS = GL_ARB_multisample GL_EXT_abgr GL_EXT_bgra GL_EXT_blend_color GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_copy_texture GL_EXT_polygon_offset GL_EXT_subtexture GL_EXT_texture_object GL_EXT_vertex_array GL_EXT_compiled_vertex_array GL_EXT_texture GL_EXT_texture3D GL_IBM_rasterpos_clip GL_ARB_point_parameters GL_EXT_draw_range_elements GL_EXT_packed_pixels GL_EXT_point_parameters GL_EXT_rescale_normal GL_EXT_separate_specular_color GL_EXT_texture_edge_clamp GL_SGIS_generate_mipmap GL_SGIS_texture_border_clamp GL_SGIS_texture_edge_clamp GL_SGIS_texture_lod GL_ARB_framebuffer_sRGB GL_ARB_multitexture GL_EXT_framebuffer_sRGB GL_IBM_multimode_draw_arrays GL_IBM_texture_mirrored_repeat GL_3DFX_texture_compression_FXT1 GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_transpose_matrix GL_EXT_blend_func_separate GL_EXT_fog_coord GL_EXT_multi_draw_arrays GL_EXT_secondary_color GL_EXT_texture_env_add GL_EXT_texture_filter_anisotropic GL_EXT_texture_lod_bias GL_INGR_blend_func_separate GL_NV_blend_square GL_NV_light_max_exponent GL_NV_texgen_reflection GL_NV_texture_env_combine4 GL_S3_s3tc GL_SUN_multi_draw_arrays GL_ARB_texture_border_clamp GL_ARB_texture_compression GL_EXT_framebuffer_object GL_EXT_texture_compression_s3tc GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_MESA_window_pos GL_NV_packed_depth_stencil GL_NV_texture_rectangle GL_ARB_depth_texture GL_ARB_occlusion_query GL_ARB_shadow GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_mirrored_repeat GL_ARB_window_pos GL_ATI_envmap_bumpmap GL_EXT_stencil_two_side GL_EXT_texture_cube_map GL_NV_depth_clamp GL_APPLE_packed_pixels GL_APPLE_vertex_array_object GL_ARB_draw_buffers GL_ARB_fragment_program GL_ARB_fragment_shader GL_ARB_shader_objects GL_ARB_vertex_program GL_ARB_vertex_shader GL_ATI_draw_buffers GL_ATI_texture_env_combine3 GL_ATI_texture_float GL_EXT_shadow_funcs GL_EXT_stencil_wrap GL_MESA_pack_invert GL_MESA_ycbcr_texture GL_NV_primitive_restart GL_ARB_depth_clamp GL_ARB_fragment_program_shadow GL_ARB_half_float_pixel GL_ARB_occlusion_query2 GL_ARB_point_sprite GL_ARB_shading_language_100 GL_ARB_sync GL_ARB_texture_non_power_of_two GL_ARB_vertex_buffer_object GL_ATI_blend_equation_separate GL_EXT_blend_equation_separate GL_OES_read_format GL_ARB_color_buffer_float GL_ARB_pixel_buffer_object GL_ARB_texture_compression_rgtc GL_ARB_texture_float GL_ARB_texture_rectangle GL_EXT_packed_float GL_EXT_pixel_buffer_object GL_EXT_texture_compression_dxt1 GL_EXT_texture_compression_rgtc GL_EXT_texture_rectangle GL_EXT_texture_sRGB GL_EXT_texture_shared_exponent GL_ARB_framebuffer_object GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXT_packed_depth_stencil GL_APPLE_object_purgeable GL_ARB_vertex_array_object GL_ATI_separate_stencil GL_EXT_draw_buffers2 GL_EXT_draw_instanced GL_EXT_gpu_program_parameters GL_EXT_texture_array GL_EXT_texture_integer GL_EXT_texture_sRGB_decode GL_EXT_timer_query GL_OES_EGL_image GL_MESA_texture_array GL_ARB_copy_buffer GL_ARB_depth_buffer_float GL_ARB_draw_instanced GL_ARB_half_float_vertex GL_ARB_instanced_arrays GL_ARB_map_buffer_range GL_ARB_texture_rg GL_ARB_texture_swizzle GL_ARB_vertex_array_bgra GL_EXT_separate_shader_objects GL_EXT_texture_swizzle GL_EXT_vertex_array_bgra GL_NV_conditional_render GL_AMD_draw_buffers_blend GL_ARB_ES2_compatibility GL_ARB_blend_func_extended GL_ARB_debug_output GL_ARB_draw_buffers_blend GL_ARB_draw_elements_base_vertex GL_ARB_explicit_attrib_location GL_ARB_fragment_coord_conventions GL_ARB_provoking_vertex GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_shader_texture_lod GL_ARB_texture_cube_map_array GL_ARB_texture_multisample GL_ARB_texture_query_lod GL_ARB_texture_rgb10_a2ui GL_ARB_uniform_buffer_object GL_ARB_vertex_type_2_10_10_10_rev GL_EXT_provoking_vertex GL_EXT_texture_snorm GL_MESA_texture_signed_rgba GL_ARB_get_program_binary GL_ARB_robustness GL_ARB_shader_bit_encoding GL_ARB_timer_query GL_ANGLE_texture_compression_dxt3 GL_ANGLE_texture_compression_dxt5 GL_ARB_internalformat_query GL_ARB_shading_language_420pack GL_ARB_shading_language_packing GL_ARB_texture_storage GL_EXT_framebuffer_multisample_blit_scaled GL_EXT_transform_feedback GL_ARB_ES3_compatibility GL_ARB_invalidate_subdata GL_ARB_texture_storage_multisample 16:33:51 T:140050152400768 ERROR: GLX: Same window as before, refreshing context 16:33:51 T:140050152400768 NOTICE: No Joystick name specified, loading default map 16:33:51 T:140050152400768 NOTICE: Previous line repeats 35 times. 16:33:51 T:140050152400768 NOTICE: Running database version Addons15 16:33:51 T:140050152400768 NOTICE: Running database version ViewModes6 16:33:51 T:140050152400768 NOTICE: Running database version Textures13 16:33:51 T:140050152400768 NOTICE: Running database version MyMusic37 16:33:51 T:140050152400768 NOTICE: Running database version MyVideos77 16:33:51 T:140050152400768 NOTICE: Running database version TV22 16:33:51 T:140050152400768 NOTICE: Running database version Epg7 16:33:51 T:140050152400768 NOTICE: start dvd mediatype detection 16:33:51 T:140050152400768 NOTICE: initializing playlistplayer 16:33:51 T:140050152400768 NOTICE: DONE initializing playlistplayer 16:33:51 T:140050150950656 NOTICE: Thread DetectDVDMedia start, auto delete: false 16:33:52 T:140050152400768 ERROR: JSONRPC: Could not parse type "GUI.Property.Value" 16:33:52 T:140050152400768 ERROR: JSONRPC: Could not parse type "Setting.Details.SettingList" 16:33:52 T:140049529161472 NOTICE: Thread JobWorker start, auto delete: true 16:33:52 T:140049529161472 NOTICE: PVRManager - starting up 16:33:52 T:140049520768768 NOTICE: Thread PVRManager start, auto delete: false 16:33:52 T:140049512376064 NOTICE: Thread PVRClient start, auto delete: false 16:33:52 T:140050152400768 NOTICE: initialize done 16:33:52 T:140050152400768 NOTICE: Running the application... 16:33:52 T:140050152400768 NOTICE: starting zeroconf publishing 16:33:52 T:140050152400768 NOTICE: ES: Starting event server 16:33:52 T:140049198847744 NOTICE: Thread JobWorker start, auto delete: true 16:33:52 T:140049182062336 NOTICE: Thread EventServer start, auto delete: false 16:33:52 T:140049182062336 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777 16:33:52 T:140049182062336 NOTICE: UDP: Listening on port 9777 16:33:52 T:140049173669632 NOTICE: Thread TCPServer start, auto delete: false 16:33:52 T:140049165276928 NOTICE: Thread JobWorker start, auto delete: true 16:33:52 T:140048817190656 NOTICE: Thread PVRGUIInfo start, auto delete: false 16:33:52 T:140048782579456 NOTICE: Thread EPGUpdater start, auto delete: false 16:33:52 T:140049520768768 NOTICE: Start - EPG thread started 16:33:52 T:140048774186752 NOTICE: Thread FileCache start, auto delete: false 16:33:54 T:140048782579456 NOTICE: Previous line repeats 2 times. 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'SAT.1 HD' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'RTL2 HD' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'kabel eins HD' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'zdf_neo HD' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'arte HD' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table '3sat HD' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'BR Nord HD' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'SWR BW HD' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'ANIXE HD' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'N24 HD' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'SPORT1 HD' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'Zing' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'UMP Movies' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'Rishtey' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'COLORS' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'Film4' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'E4' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'STV' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'BBC Two HD' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'BBC THREE' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'BBC FOUR' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'ITV HD' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'ITV2' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'ITV4' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'Channel 4' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'Channel 5' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table '5 USA' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'ProSieben MAXX' 16:33:54 T:140048782579456 WARNING: EPG - UpdateFromScraper - channel not found, can't update 16:33:54 T:140048782579456 ERROR: EPG - Update - failed to update table 'DMAX HD' 16:34:01 T:140050152400768 NOTICE: Log level changed to 2 16:34:01 T:140050152400768 NOTICE: Enabled debug logging due to GUI setting. Level 2. 16:34:01 T:140050152400768 DEBUG: ------ Window Init () ------ 16:34:06 T:140050152400768 DEBUG: ProcessMouse: trying mouse action leftclick 16:34:06 T:140050152400768 DEBUG: ExecuteXBMCAction : Translating action string 16:34:06 T:140050152400768 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:34:06 T:140050152400768 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 16:34:06 T:140050152400768 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:34:06 T:140050152400768 DEBUG: ------ Window Init (Settings.xml) ------ 16:34:08 T:140050152400768 DEBUG: ProcessMouse: trying mouse action leftclick 16:34:08 T:140050152400768 DEBUG: ExecuteXBMCAction : Translating action string 16:34:08 T:140050152400768 DEBUG: Activating window ID: 10017 16:34:09 T:140050152400768 DEBUG: ------ Window Deinit (Settings.xml) ------ 16:34:09 T:140050152400768 DEBUG: ------ Window Init (SettingsCategory.xml) ------ 16:34:19 T:140050152400768 DEBUG: ProcessMouse: trying mouse action leftclick 16:34:19 T:140050152400768 DEBUG: ExecuteXBMCAction : Translating action string 16:34:19 T:140050152400768 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:34:19 T:140050152400768 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 16:34:19 T:140050152400768 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:34:19 T:140050152400768 DEBUG: ------ Window Init (Settings.xml) ------ 16:34:20 T:140050152400768 DEBUG: ProcessMouse: trying mouse action leftclick 16:34:20 T:140050152400768 DEBUG: ExecuteXBMCAction : Translating action string 16:34:20 T:140050152400768 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:34:21 T:140050152400768 DEBUG: ------ Window Deinit (Settings.xml) ------ 16:34:21 T:140050152400768 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:34:21 T:140050152400768 DEBUG: ------ Window Init (Home.xml) ------ 16:34:21 T:140050152400768 DEBUG: SECTION:UnloadDelayed(DLL: libcrystalhd.so.3) 16:34:21 T:140050152400768 DEBUG: Unloading: libcrystalhd.so.3 16:34:22 T:140050152400768 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86_64-linux.so) 16:34:22 T:140050152400768 DEBUG: Unloading: ImageLib-x86_64-linux.so 16:34:22 T:140048774186752 NOTICE: Thread JobWorker start, auto delete: true 16:34:23 T:140050152400768 INFO: CheckIdle - Closing session to http://mirrors.xbmc.org (easy=0x7f5fb001b950, multi=0x7f5fb0018660) 16:34:24 T:140050152400768 INFO: CheckIdle - Closing session to http://dl.dropbox.com (easy=0x7f5fb003dc40, multi=0x7f5fb00469c0) 16:34:27 T:140050152400768 DEBUG: ProcessMouse: trying mouse action leftclick 16:34:27 T:140050152400768 DEBUG: ExecuteXBMCAction : Translating action string 16:34:27 T:140050152400768 DEBUG: Activating window ID: 10601 16:34:27 T:140050152400768 DEBUG: ------ Window Deinit (Home.xml) ------ 16:34:27 T:140050152400768 DEBUG: ------ Window Init (MyPVR.xml) ------ 16:34:27 T:140050152400768 INFO: Loading skin file: MyPVR.xml, load type: LOAD_EVERY_TIME 16:34:27 T:140050152400768 DEBUG: Using already stored xml root node for /usr/share/xbmc/addons/skin.confluence/720p/MyPVR.xml 16:34:27 T:140050152400768 DEBUG: CGUIMediaWindow::GetDirectory () 16:34:27 T:140050152400768 DEBUG: ParentPath = [] 16:34:27 T:140050152400768 DEBUG: CGUIWindowPVRCommon - OnMessageFocus - focus set to window 'tv' 16:34:27 T:140050152400768 DEBUG: CGUIWindowPVRChannels - UpdateData - update window 'tv'. set view to 11 16:34:27 T:140050152400768 DEBUG: CGUIMediaWindow::GetDirectory (pvr://channels/tv/All TV channels/) 16:34:27 T:140050152400768 DEBUG: ParentPath = [] 16:34:27 T:140049198847744 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/tv/All TV channels) 16:34:27 T:140050152400768 DEBUG: CGUIWindowPVRCommon - OnMessageFocus - focus set to window 'tv' 16:34:27 T:140050152400768 DEBUG: CGUIWindowPVRCommon - OnMessageFocus - focus set to window 'recordings' 16:34:27 T:140050152400768 DEBUG: CGUIWindowPVRRecordings - UpdateData - update window 'recordings'. set view to 13 16:34:27 T:140050152400768 DEBUG: CGUIMediaWindow::GetDirectory (pvr://recordings/) 16:34:27 T:140050152400768 DEBUG: ParentPath = [] 16:34:27 T:140049165276928 DEBUG: CPVRDirectory::GetDirectory(pvr://recordings) 16:34:27 T:140050152400768 DEBUG: RunQuery took 0 ms for 23 items query: SELECT files.strFilename, files.playCount, bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files LEFT JOIN bookmark ON files.idFile = bookmark.idFile AND bookmark.type = 1 WHERE files.idPath=3 16:34:27 T:140048808797952 NOTICE: Thread BackgroundLoader start, auto delete: false 16:34:27 T:140048808797952 DEBUG: Thread BackgroundLoader 140048808797952 terminating 16:34:28 T:140050152400768 DEBUG: ProcessMouse: trying mouse action leftclick 16:34:28 T:140050152400768 DEBUG: CPlayerCoreFactory::GetPlayers(pvr://recordings/Spuren des B?sen - Zauberberg, TV (ZDF HD), 20140113_191500.pvr) 16:34:28 T:140050152400768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 16:34:28 T:140050152400768 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 16:34:28 T:140050152400768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv 16:34:28 T:140050152400768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp 16:34:28 T:140050152400768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 16:34:28 T:140050152400768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 16:34:28 T:140050152400768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 16:34:28 T:140050152400768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 16:34:28 T:140050152400768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 16:34:28 T:140050152400768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 16:34:28 T:140050152400768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 16:34:28 T:140050152400768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 16:34:28 T:140050152400768 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 16:34:28 T:140050152400768 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 16:34:28 T:140050152400768 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1) 16:34:28 T:140050152400768 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 16:34:28 T:140050152400768 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 16:34:28 T:140050152400768 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1) 16:34:28 T:140050152400768 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 16:34:28 T:140050152400768 NOTICE: DVDPlayer: Opening: pvr://recordings/Spuren des B?sen - Zauberberg, TV (ZDF HD), 20140113_191500.pvr 16:34:28 T:140050152400768 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 16:34:28 T:140050152400768 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec 16:34:28 T:140050152400768 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:34:28 T:140050152400768 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 16:34:28 T:140050152400768 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/swscale-2-x86_64-linux.so) 16:34:28 T:140050152400768 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/swscale-2-x86_64-linux.so 16:34:28 T:140048808797952 NOTICE: Thread DVDPlayer start, auto delete: false 16:34:28 T:140048808797952 NOTICE: Creating InputStream 16:34:28 T:140048808797952 DEBUG: PVRManager - OpenRecordedStream - opening recorded stream '' 16:34:28 T:140048808797952 DEBUG: Open - Recording has started on filename pvr://recordings/Spuren des B?sen - Zauberberg, TV (ZDF HD), 20140113_191500.pvr 16:34:28 T:140048808797952 DEBUG: CDVDInputStreamPVRManager::Open - stream opened: pvr://recordings/Spuren des B?sen - Zauberberg, TV (ZDF HD), 20140113_191500.pvr 16:34:28 T:140048808797952 NOTICE: Creating Demuxer 16:34:28 T:140048808797952 DEBUG: Open - probing detected format [mpegts] 16:34:28 T:140048808797952 DEBUG: Open - avformat_find_stream_info starting 16:34:28 T:140048808797952 DEBUG: ffmpeg[A77FE700]: [h264] Increasing reorder buffer to 1 16:34:28 T:140048808797952 DEBUG: ffmpeg[A77FE700]: [h264] Increasing reorder buffer to 2 16:34:29 T:140050152400768 DEBUG: ------ Window Init (DialogBusy.xml) ------ 16:34:29 T:140048808797952 DEBUG: ffmpeg[A77FE700]: [mpegts] Stream #5: not enough frames to estimate rate; consider increasing probesize 16:34:29 T:140048808797952 DEBUG: ffmpeg[A77FE700]: [NULL] start time is not set in estimate_timings_from_pts 16:34:29 T:140048808797952 DEBUG: ffmpeg[A77FE700]: [mpegts] PES packet size mismatch 16:34:29 T:140048808797952 DEBUG: Previous line repeats 3 times. 16:34:29 T:140048808797952 DEBUG: Open - av_find_stream_info finished 16:34:29 T:140048808797952 INFO: ffmpeg[A77FE700]: Input #0, mpegts, from 'pvr://recordings/Spuren des B?sen - Zauberberg, TV (ZDF HD), 20140113_191500.pvr': 16:34:29 T:140048808797952 INFO: ffmpeg[A77FE700]: Duration: 01:30:00.49, start: 65650.051456, bitrate: 13442 kb/s 16:34:29 T:140048808797952 INFO: ffmpeg[A77FE700]: Program 132 16:34:29 T:140048808797952 INFO: ffmpeg[A77FE700]: Stream #0:0[0x17de]: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p, 1280x720 [SAR 1:1 DAR 16:9], 50 fps, 50 tbr, 90k tbn, 100 tbc 16:34:29 T:140048808797952 INFO: ffmpeg[A77FE700]: Stream #0:1[0x17e8](deu): Audio: mp2 ([3][0][0][0] / 0x0003), 48000 Hz, stereo, s16p, 256 kb/s 16:34:29 T:140048808797952 INFO: ffmpeg[A77FE700]: Stream #0:2[0x17e9](mis): Audio: mp2 ([3][0][0][0] / 0x0003), 48000 Hz, stereo, s16p, 192 kb/s 16:34:29 T:140048808797952 INFO: ffmpeg[A77FE700]: Stream #0:3[0x17eb](mul): Audio: mp2 ([3][0][0][0] / 0x0003), 48000 Hz, stereo, s16p, 192 kb/s 16:34:29 T:140048808797952 INFO: ffmpeg[A77FE700]: Stream #0:4[0x17ea](deu): Audio: ac3 ([6][0][0][0] / 0x0006), 48000 Hz, 5.1(side), fltp, 448 kb/s 16:34:29 T:140048808797952 INFO: ffmpeg[A77FE700]: Stream #0:5[0x17f3](deu): Subtitle: dvb_subtitle ([6][0][0][0] / 0x0006) 16:34:29 T:140048808797952 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0 16:34:29 T:140048808797952 DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1 16:34:29 T:140048808797952 DEBUG: CDVDDemuxFFmpeg::AddStream(2, ...) -> 2 16:34:29 T:140048808797952 DEBUG: CDVDDemuxFFmpeg::AddStream(3, ...) -> 3 16:34:29 T:140048808797952 DEBUG: CDVDDemuxFFmpeg::AddStream(4, ...) -> 4 16:34:29 T:140048808797952 DEBUG: CDVDDemuxFFmpeg::AddStream(5, ...) -> 5 16:34:29 T:140048808797952 NOTICE: Opening video stream: 0 source: 256 16:34:29 T:140048808797952 NOTICE: Creating video codec with codec id: 28 16:34:29 T:140048808797952 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:yes AMCodec:no MediaCodec:no OpenMax:no libstagefright:no VDPAU:yes VAAPI:yes 16:34:29 T:140048808797952 DEBUG: FactoryCodec - Video: - Opening 16:34:29 T:140048808797952 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/postproc-52-x86_64-linux.so) 16:34:29 T:140048808797952 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/postproc-52-x86_64-linux.so 16:34:29 T:140048808797952 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avfilter-3-x86_64-linux.so) 16:34:29 T:140048808797952 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avfilter-3-x86_64-linux.so 16:34:29 T:140048808797952 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 16:34:29 T:140048808797952 DEBUG: FactoryCodec - Video: ff-h264 - Opened 16:34:29 T:140048808797952 NOTICE: Creating video thread 16:34:29 T:140048473253632 NOTICE: Thread DVDPlayerVideo start, auto delete: false 16:34:29 T:140048473253632 NOTICE: running thread: video_thread 16:34:29 T:140048808797952 NOTICE: Opening audio stream: 4 source: 256 16:34:29 T:140048808797952 NOTICE: Finding audio codec for: 86019 16:34:29 T:140048473253632 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 16:34:29 T:140048808797952 DEBUG: FactoryCodec - Audio: passthrough - Opening 16:34:29 T:140048808797952 DEBUG: FactoryCodec - Audio: passthrough - Opened 16:34:29 T:140048808797952 NOTICE: Creating audio thread 16:34:29 T:140048464860928 NOTICE: Thread DVDPlayerAudio start, auto delete: false 16:34:29 T:140048464860928 NOTICE: running thread: CDVDPlayerAudio::Process() 16:34:29 T:140048808797952 NOTICE: Opening Subtitle stream: 5 source: 256 16:34:29 T:140048808797952 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opening 16:34:29 T:140048808797952 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opened 16:34:29 T:140048808797952 DEBUG: ReadEditDecisionLists - Checking for edit decision list (EDL) for PVR recording: pvr://recordings/Spuren des B?sen - Zauberberg, TV (ZDF HD), 20140113_191500.pvr 16:34:29 T:140048808797952 DEBUG: ReadPvr - Reading Edl for recording: Spuren des B?sen - Zauberberg 16:34:29 T:140048808797952 DEBUG: OnPlayBackStarted : play state was 1, starting 1 16:34:29 T:140048808797952 DEBUG: CDVDPlayer::SetCaching - caching state 3 16:34:29 T:140048473253632 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(430244.000003, 1) 16:34:29 T:140048473253632 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback 16:34:29 T:140048473253632 NOTICE: fps: 50.000000, pwidth: 1280, pheight: 720, dwidth: 1280, dheight: 720 16:34:29 T:140048473253632 DEBUG: OutputPicture - change configuration. 1280x720. framerate: 50.00. format: YV12 16:34:29 T:140048473253632 NOTICE: Display resolution ADJUST : HDMI2: 1920x1080 @ 50.00Hz (18) (weight: 0.000) 16:34:29 T:140048473253632 DEBUG: CXBMCRenderManager::Configure - 3 16:34:29 T:140050152400768 NOTICE: Using GL_TEXTURE_2D 16:34:29 T:140050152400768 DEBUG: GL: Requested render method: 3 16:34:29 T:140050152400768 NOTICE: GL: Shaders support not present, falling back to SW mode 16:34:29 T:140050152400768 NOTICE: GL: NPOT texture support detected 16:34:29 T:140048808797952 DEBUG: CDVDPlayer::HandleMessages - player started 2 16:34:29 T:140050152400768 DEBUG: Activating window ID: 12005 16:34:29 T:140050152400768 DEBUG: ------ Window Deinit (MyPVR.xml) ------ 16:34:29 T:140050152400768 DEBUG: OnLostDevice - notify display change event 16:34:29 T:140050152400768 DEBUG: Flush - flushing renderer 16:34:29 T:140050152400768 INFO: XRANDR: /usr/lib/xbmc/xbmc-xrandr --output HDMI2 --mode 0xc0 16:34:29 T:140048808797952 DEBUG: CDVDDemuxFFmpeg::AddStream(4, ...) -> 4 16:34:29 T:140048808797952 NOTICE: Opening audio stream: 4 source: 256 16:34:29 T:140048808797952 NOTICE: Finding audio codec for: 86019 16:34:29 T:140048808797952 DEBUG: FactoryCodec - Audio: passthrough - Opening 16:34:29 T:140048808797952 DEBUG: FactoryCodec - Audio: passthrough - Opened 16:34:29 T:140048464860928 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(97877.333334, 0) 16:34:29 T:140048464860928 INFO: CAEStreamInfo::SyncAC3 - AC3 stream detected (2 channels, 48000Hz) 16:34:29 T:140048464860928 NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, pass-through) 16:34:29 T:140048464860928 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback 16:34:29 T:140049853400832 INFO: CActiveAE::OpenSink - sink incompatible, re-starting 16:34:29 T:140048808797952 DEBUG: CDVDDemuxFFmpeg::AddStream(4, ...) -> 4 16:34:29 T:140048808797952 NOTICE: Opening audio stream: 4 source: 256 16:34:29 T:140048808797952 NOTICE: Finding audio codec for: 86019 16:34:29 T:140048808797952 DEBUG: FactoryCodec - Audio: passthrough - Opening 16:34:29 T:140048808797952 DEBUG: FactoryCodec - Audio: passthrough - Opened 16:34:29 T:140049853400832 DEBUG: CActiveAE::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=1 16:34:29 T:140049853400832 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=1" 16:34:29 T:140049853400832 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=1,AES0=0x06,AES1=0x82,AES2=0x00,AES3=0x02" 16:34:29 T:140049853400832 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600 16:34:29 T:140049853400832 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600 16:34:29 T:140049853400832 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 16:34:29 T:140049853400832 DEBUG: CActiveAE::OpenSink - ALSA Initialized: 16:34:29 T:140049853400832 DEBUG: Output Device : HDA Intel PCH 16:34:29 T:140049853400832 DEBUG: Sample Rate : 48000 16:34:29 T:140049853400832 DEBUG: Sample Format : AE_FMT_S16NE 16:34:29 T:140049853400832 DEBUG: Channel Count : 2 16:34:29 T:140049853400832 DEBUG: Channel Layout: FL,FR 16:34:29 T:140049853400832 DEBUG: Frames : 2400 16:34:29 T:140049853400832 DEBUG: Frame Samples : 4800 16:34:29 T:140049853400832 DEBUG: Frame Size : 4 16:34:29 T:140049822430976 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 16:34:29 T:140048808797952 DEBUG: Previous line repeats 1 times. 16:34:29 T:140048808797952 DEBUG: CDVDPlayer::HandleMessages - player started 1 16:34:29 T:140048808797952 DEBUG: Previous line repeats 1 times. 16:34:29 T:140048808797952 DEBUG: CDVDPlayer::SetCaching - caching state 0 16:34:29 T:140048464860928 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:430300.547003, should be:-262.845011, error:-430563.392014 16:34:29 T:140048473253632 INFO: ffmpeg[937FE700]: [h264] Cannot parallelize deblocking type 1, decoding such frames in sequential order 16:34:29 T:140048473253632 DEBUG: ffmpeg[937FE700]: [h264] Increasing reorder buffer to 1 16:34:30 T:140050152400768 ERROR: GLX: Same window as before, refreshing context 16:34:30 T:140050152400768 INFO: GL: Maximum texture width: 8192 16:34:30 T:140050152400768 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec 16:34:30 T:140050152400768 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 16:34:30 T:140050152400768 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY 16:34:30 T:140048464860928 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:247819.588989, should be:147380.143151, error:-100439.445838 16:34:30 T:140050152400768 DEBUG: PlayFile : OpenFile succeed, play state 2 16:34:30 T:140050152400768 DEBUG: OnPlayBackStarted : play state was 2, starting 0 16:34:30 T:140050152400768 NOTICE: Using GL_TEXTURE_2D 16:34:30 T:140050152400768 DEBUG: GL: Requested render method: 3 16:34:30 T:140050152400768 NOTICE: GL: Shaders support not present, falling back to SW mode 16:34:30 T:140050152400768 NOTICE: GL: NPOT texture support detected 16:34:30 T:140050152400768 DEBUG: CheckDisplayEvents: Received RandR event 89 16:34:30 T:140050152400768 DEBUG: CheckDisplayEvents - notify display reset event 16:34:30 T:140050152400768 DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://recordings/Spuren des B?sen - Zauberberg, TV (ZDF HD), 20140113_191500.pvr) 16:34:30 T:140048473253632 DEBUG: ffmpeg[937FE700]: [h264] Increasing reorder buffer to 2 16:34:30 T:140050152400768 DEBUG: GetMovieId (pvr://recordings/Spuren des B?sen - Zauberberg, TV (ZDF HD), 20140113_191500.pvr), query = select idMovie from movie where idFile=614 16:34:30 T:140050152400768 DEBUG: GetEpisodeId (pvr://recordings/Spuren des B?sen - Zauberberg, TV (ZDF HD), 20140113_191500.pvr), query = select idEpisode from episode where idFile=614 16:34:30 T:140050152400768 DEBUG: GetMusicVideoId (pvr://recordings/Spuren des B?sen - Zauberberg, TV (ZDF HD), 20140113_191500.pvr), query = select idMVideo from musicvideo where idFile=614 16:34:30 T:140050152400768 DEBUG: GetMovieId (pvr://recordings/Spuren des B?sen - Zauberberg, TV (ZDF HD), 20140113_191500.pvr), query = select idMovie from movie where idFile=614 16:34:30 T:140050152400768 DEBUG: GetEpisodeId (pvr://recordings/Spuren des B?sen - Zauberberg, TV (ZDF HD), 20140113_191500.pvr), query = select idEpisode from episode where idFile=614 16:34:30 T:140050152400768 DEBUG: GetMusicVideoId (pvr://recordings/Spuren des B?sen - Zauberberg, TV (ZDF HD), 20140113_191500.pvr), query = select idMVideo from musicvideo where idFile=614 16:34:30 T:140050152400768 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 16:34:30 T:140050152400768 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 16:34:30 T:140050152400768 NOTICE: GL: Using GL_ARB_pixel_buffer_object 16:34:30 T:140050152400768 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 16:34:30 T:140048464860928 INFO: CAEStreamInfo::SyncAC3 - AC3 stream detected (2 channels, 48000Hz) 16:34:30 T:140048464860928 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback 16:34:30 T:140048464860928 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:646789.220151, should be:597103.498328, error:-49685.721823 16:34:30 T:140048808797952 DEBUG: CDVDPlayer::HandleMessages - player started 1 16:34:32 T:140048473253632 DEBUG: CPullupCorrection: detected pattern of length 1: 20000.00, frameduration: 20000.000000 16:34:34 T:140050152400768 DEBUG: ------ Window Deinit (Pointer.xml) ------ 16:34:35 T:140050152400768 DEBUG: Keyboard: scancode: 0x20, sym: 0x006f, unicode: 0x006f, modifier: 0x1000 16:34:35 T:140050152400768 DEBUG: OnKey: o (0xf04f) pressed, action is CodecInfo 16:34:37 T:140050152400768 DEBUG: Keyboard: scancode: 0x20, sym: 0x006f, unicode: 0x006f, modifier: 0x1000 16:34:37 T:140050152400768 DEBUG: OnKey: o (0xf04f) pressed, action is CodecInfo 16:34:37 T:140050152400768 DEBUG: ------ Window Init (VideoOSD.xml) ------ 16:34:37 T:140050152400768 INFO: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY 16:34:37 T:140050152400768 DEBUG: ------ Window Init (Pointer.xml) ------ 16:34:38 T:140050152400768 DEBUG: ProcessMouse: trying mouse action leftclick 16:34:38 T:140050152400768 DEBUG: ExecuteXBMCAction : Translating action string 16:34:38 T:140050152400768 NOTICE: CDVDPlayer::CloseFile() 16:34:38 T:140050152400768 NOTICE: DVDPlayer: waiting for threads to exit 16:34:38 T:140048808797952 NOTICE: CDVDPlayer::OnExit() 16:34:38 T:140048808797952 NOTICE: DVDPlayer: closing audio stream 16:34:38 T:140048808797952 NOTICE: Closing audio stream 16:34:38 T:140048808797952 NOTICE: Waiting for audio thread to exit 16:34:39 T:140048464860928 NOTICE: thread end: CDVDPlayerAudio::OnExit() 16:34:39 T:140048464860928 DEBUG: Thread DVDPlayerAudio 140048464860928 terminating 16:34:39 T:140048808797952 NOTICE: Closing audio device 16:34:39 T:140049822430976 DEBUG: CActiveAE::DiscardStream - audio stream deleted 16:34:39 T:140049822430976 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 16:34:39 T:140048808797952 NOTICE: Deleting audio codec 16:34:39 T:140048808797952 NOTICE: DVDPlayer: closing video stream 16:34:39 T:140048808797952 NOTICE: Closing video stream 16:34:39 T:140048808797952 NOTICE: waiting for video thread to exit 16:34:39 T:140049822430976 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 16:34:39 T:140049853400832 INFO: CActiveAE::OpenSink - sink incompatible, re-starting 16:34:39 T:140048473253632 NOTICE: thread end: video_thread 16:34:39 T:140048473253632 DEBUG: Thread DVDPlayerVideo 140048473253632 terminating 16:34:39 T:140048808797952 NOTICE: deleting video codec 16:34:39 T:140048808797952 NOTICE: DVDPlayer: closing subtitle stream 16:34:39 T:140048808797952 NOTICE: Closing subtitle stream 16:34:39 T:140048808797952 NOTICE: CDVDPlayer::OnExit() deleting demuxer 16:34:39 T:140048808797952 NOTICE: CDVDPlayer::OnExit() deleting input stream 16:34:39 T:140048808797952 DEBUG: CDVDInputStreamPVRManager::Close - stream closed 16:34:39 T:140048808797952 DEBUG: OnPlayBackStopped : play state was 2, starting 0 16:34:39 T:140048808797952 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 16:34:39 T:140048808797952 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 16:34:39 T:140048808797952 DEBUG: Thread DVDPlayer 140048808797952 terminating 16:34:39 T:140050152400768 NOTICE: DVDPlayer: finished waiting 16:34:39 T:140050152400768 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:34:39 T:140050152400768 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:34:39 T:140050152400768 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 16:34:39 T:140050152400768 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 16:34:39 T:140050152400768 DEBUG: OnLostDevice - notify display change event 16:34:39 T:140050152400768 DEBUG: Flush - flushing renderer 16:34:39 T:140050152400768 INFO: XRANDR: /usr/lib/xbmc/xbmc-xrandr --output HDMI2 --mode 0x4c 16:34:39 T:140049853400832 DEBUG: CActiveAE::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=1 16:34:39 T:140049853400832 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=1" 16:34:39 T:140049853400832 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=1,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 16:34:39 T:140049853400832 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 16:34:39 T:140049853400832 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 16:34:39 T:140049853400832 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2048, bufferSize 8192 16:34:39 T:140049853400832 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192 16:34:39 T:140049853400832 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 186 ms 16:34:39 T:140049853400832 DEBUG: CActiveAE::OpenSink - ALSA Initialized: 16:34:39 T:140049853400832 DEBUG: Output Device : HDA Intel PCH 16:34:39 T:140049853400832 DEBUG: Sample Rate : 44100 16:34:39 T:140049853400832 DEBUG: Sample Format : AE_FMT_S32NE 16:34:39 T:140049853400832 DEBUG: Channel Count : 2 16:34:39 T:140049853400832 DEBUG: Channel Layout: FL,FR 16:34:39 T:140049853400832 DEBUG: Frames : 2048 16:34:39 T:140049853400832 DEBUG: Frame Samples : 4096 16:34:39 T:140049853400832 DEBUG: Frame Size : 8 16:34:39 T:140049822430976 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 16:34:39 T:140050152400768 ERROR: GLX: Same window as before, refreshing context 16:34:39 T:140050152400768 INFO: GL: Maximum texture width: 8192 16:34:39 T:140050152400768 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec 16:34:39 T:140050152400768 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:34:39 T:140050152400768 DEBUG: ------ Window Init (MyPVR.xml) ------ 16:34:39 T:140050152400768 INFO: Loading skin file: MyPVR.xml, load type: LOAD_EVERY_TIME 16:34:39 T:140050152400768 DEBUG: Using already stored xml root node for /usr/share/xbmc/addons/skin.confluence/720p/MyPVR.xml 16:34:39 T:140050152400768 DEBUG: CGUIMediaWindow::GetDirectory (pvr://recordings/) 16:34:39 T:140050152400768 DEBUG: ParentPath = [pvr://recordings/] 16:34:39 T:140050152400768 DEBUG: CPVRDirectory::GetDirectory(pvr://recordings) 16:34:39 T:140050152400768 DEBUG: CGUIWindowPVRCommon - OnMessageFocus - focus set to window 'recordings' 16:34:39 T:140050152400768 DEBUG: CGUIWindowPVRRecordings - UpdateData - update window 'recordings'. set view to 13 16:34:39 T:140050152400768 DEBUG: CGUIMediaWindow::GetDirectory (pvr://recordings/) 16:34:39 T:140050152400768 DEBUG: ParentPath = [pvr://recordings/] 16:34:39 T:140049529161472 DEBUG: CPVRDirectory::GetDirectory(pvr://recordings) 16:34:39 T:140050152400768 DEBUG: RunQuery took 0 ms for 23 items query: SELECT files.strFilename, files.playCount, bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files LEFT JOIN bookmark ON files.idFile = bookmark.idFile AND bookmark.type = 1 WHERE files.idPath=3 16:34:39 T:140048808797952 NOTICE: Thread BackgroundLoader start, auto delete: false 16:34:39 T:140050152400768 DEBUG: CheckDisplayEvents: Received RandR event 89 16:34:39 T:140050152400768 DEBUG: CheckDisplayEvents - notify display reset event 16:34:39 T:140048808797952 DEBUG: Thread BackgroundLoader 140048808797952 terminating 16:34:39 T:140050152400768 NOTICE: CDVDPlayer::CloseFile() 16:34:39 T:140050152400768 NOTICE: DVDPlayer: waiting for threads to exit 16:34:39 T:140050152400768 NOTICE: DVDPlayer: finished waiting 16:34:39 T:140050152400768 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:34:39 T:140050152400768 NOTICE: CDVDPlayer::CloseFile() 16:34:39 T:140050152400768 NOTICE: DVDPlayer: waiting for threads to exit 16:34:39 T:140050152400768 NOTICE: DVDPlayer: finished waiting 16:34:39 T:140050152400768 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:34:39 T:140049529161472 DEBUG: DoWork - Saving file state for video item pvr://recordings/Spuren des B?sen - Zauberberg, TV (ZDF HD), 20140113_191500.pvr 16:34:43 T:140050152400768 DEBUG: ------ Window Deinit (Pointer.xml) ------ 16:35:09 T:140050152400768 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/dvdplayer/postproc-52-x86_64-linux.so) 16:35:09 T:140050152400768 DEBUG: Unloading: postproc-52-x86_64-linux.so 16:35:09 T:140049198847744 DEBUG: Thread JobWorker 140049198847744 terminating (autodelete) 16:35:09 T:140049165276928 DEBUG: Thread JobWorker 140049165276928 terminating (autodelete) 16:35:09 T:140048774186752 DEBUG: Thread JobWorker 140048774186752 terminating (autodelete) 16:35:09 T:140049529161472 DEBUG: Thread JobWorker 140049529161472 terminating (autodelete) 16:35:10 T:140050152400768 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/dvdplayer/avfilter-3-x86_64-linux.so) 16:35:10 T:140050152400768 DEBUG: Unloading: avfilter-3-x86_64-linux.so 16:37:39 T:140050152400768 DEBUG: CAnnouncementManager - Announcement: OnScreensaverActivated from xbmc 16:37:39 T:140050152400768 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnScreensaverActivated 16:37:39 T:140050152400768 DEBUG: ------ Window Init () ------