team-xbmc team mailing list archive

Thread
Date

Bug#683703: xbmc: Video playback fails when VAAPI is enabled and a VAAPI driver is installed

To: 683703@xxxxxxxxxxxxxxx
From: Matt Horan <matt@xxxxxxxxxxxxx>
Date: Thu, 9 Aug 2012 23:21:27 -0400
Reply-to: Matt Horan <matt@xxxxxxxxxxxxx>, 683703@xxxxxxxxxxxxxxx
Resent-cc: Team XBMC <team-xbmc@xxxxxxxxxxxxxxxxxxx>
Resent-date: Fri, 10 Aug 2012 03:24:01 +0000
Resent-from: Matt Horan <matt@xxxxxxxxxxxxx>
Resent-message-id: <handler.683703.B683703.13445688903847@xxxxxxxxxxxxxxx>
Resent-sender: Debian BTS <debbugs@xxxxxxxxxxxxxxxxxxxx>
Resent-to: debian-bugs-dist@xxxxxxxxxxxxxxxx
User-agent: Mutt/1.4.2.3i

I've attached two log files.  upstream.log corresponds to failed
playback using the Debian package with VAAPI is enabled.  custom.log
corresponds to the successful playback with my custom built pacakge,
where xbmc is using its own internal libraries.

Note that the log shows Mesa version 8.0.3, however I later confirmed
that the issue also exists with Mesa 8.0.4.

-- 
Matt Horan matt@xxxxxxxxxxxxx http://matthoran.com/

23:02:31 T:140008146835360  NOTICE: -----------------------------------------------------------------------
23:02:31 T:140008146835360  NOTICE: Starting XBMC (11.0 Git:20120510-82388d5), Platform: Linux (Debian GNU/Linux testing (wheezy), 3.6.0-rc1+ x86_64). Built on Aug  9 2012
23:02:31 T:140008146835360  NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc
23:02:31 T:140008146835360  NOTICE: special://xbmcbin/ is mapped to: /usr/lib/xbmc
23:02:31 T:140008146835360  NOTICE: special://masterprofile/ is mapped to: /home/mhoran/.xbmc/userdata
23:02:31 T:140008146835360  NOTICE: special://home/ is mapped to: /home/mhoran/.xbmc
23:02:31 T:140008146835360  NOTICE: special://temp/ is mapped to: /home/mhoran/.xbmc/temp
23:02:31 T:140008146835360  NOTICE: The executable running is: /usr/lib/xbmc/xbmc.bin
23:02:31 T:140008146835360  NOTICE: Local hostname: challenger
23:02:31 T:140008146835360  NOTICE: Log File is located: /home/mhoran/.xbmc/temp/xbmc.log
23:02:31 T:140008146835360  NOTICE: -----------------------------------------------------------------------
23:02:31 T:140008146835360  NOTICE: Setup SDL
23:02:32 T:140008146835360  NOTICE: load settings...
23:02:32 T:140008146835360  NOTICE: special://profile/ is mapped to: special://masterprofile/
23:02:32 T:140008146835360  NOTICE: loading special://masterprofile/guisettings.xml
23:02:32 T:140008146835360  NOTICE: Getting hardware information now...
23:02:32 T:140008146835360  NOTICE: Checking resolution 12
23:02:32 T:140008146835360  NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
23:02:32 T:140008146835360  NOTICE: Loaded playercorefactory configuration
23:02:32 T:140008146835360  NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
23:02:32 T:140008146835360  NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
23:02:32 T:140008146835360  NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml)
23:02:32 T:140008146835360  NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
23:02:32 T:140008146835360  NOTICE: Default DVD Player: dvdplayer
23:02:32 T:140008146835360  NOTICE: Default Video Player: dvdplayer
23:02:32 T:140008146835360  NOTICE: Default Audio Player: paplayer
23:02:32 T:140008146835360  NOTICE: Disabled debug logging due to GUI setting. Level 0.
23:02:32 T:140008146835360  NOTICE: Log level changed to 0
23:02:32 T:140008146835360  NOTICE: Loading media sources from special://masterprofile/sources.xml
23:02:33 T:140008146835360  NOTICE: Using visual 0x22
23:02:33 T:140008146835360  NOTICE: GL_VENDOR = Tungsten Graphics, Inc
23:02:33 T:140008146835360  NOTICE: GL_RENDERER = Mesa DRI Intel(R) Sandybridge Desktop
23:02:33 T:140008146835360  NOTICE: GL_VERSION = 3.0 Mesa 8.0.4
23:02:33 T:140008146835360  NOTICE: GL_SHADING_LANGUAGE_VERSION = 1.30
23:02:33 T:140008146835360  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_SUN_multi_draw_arrays GL_ARB_texture_border_clamp GL_ARB_texture_compression GL_EXT_framebuffer_object GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_MESA_window_pos GL_NV_packed_depth_stencil GL_NV_texture_rectangle GL_NV_vertex_program 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_NV_vertex_program1_1 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_ARB_depth_clamp GL_ARB_fragment_program_shadow GL_ARB_half_float_pixel 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_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_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_half_float_vertex 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_ARB_ES2_compatibility 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_EXT_provoking_vertex GL_EXT_texture_snorm GL_MESA_texture_signed_rgba GL_ARB_robustness GL_EXT_transform_feedback
23:02:33 T:140008146835360   ERROR: GLX: Same window as before, refreshing context
23:02:33 T:140008146835360  NOTICE: UDisks: Added /mnt/storage
23:02:33 T:140008146835360  NOTICE: start dvd mediatype detection
23:02:33 T:140008146835360  NOTICE: initializing playlistplayer
23:02:33 T:140008146835360  NOTICE: DONE initializing playlistplayer
23:02:33 T:140008146835360  NOTICE: initialize done
23:02:33 T:140008146835360  NOTICE: Running the application...
23:02:33 T:140007757829888  NOTICE: -->Python Interpreter Initialized<--
23:02:33 T:140007612344064  NOTICE: -->Python Interpreter Initialized<--
23:02:33 T:140008146835360  NOTICE: ES: Starting event server
23:02:33 T:140007587165952  NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
23:02:33 T:140007587165952  NOTICE: UDP: Listening on port 9777
23:02:33 T:140008146835360  NOTICE: starting zeroconf publishing
23:02:33 T:140007757829888  NOTICE: Artwork Downloader: ## Service - Run at startup: False
23:02:33 T:140007757829888  NOTICE: Artwork Downloader: ## Service - Delayed startup: -- minutes
23:02:33 T:140007757829888  NOTICE: Artwork Downloader: ## Service - Run as service: False
23:02:33 T:140007757829888  NOTICE: Artwork Downloader: ## Service - Time: 00:00
23:02:33 T:140007612344064  NOTICE:  StorageServer Module loaded RUN
23:02:33 T:140007612344064  NOTICE: StorageClient-1.1.0 Starting server
23:02:33 T:140007612344064  NOTICE: StorageServer-1.1.0 Storage Server starting /home/mhoran/.xbmc/userdata/Database/commoncache.db
23:02:33 T:140007570380544  NOTICE: -->Python Interpreter Initialized<--
23:02:39 T:140007570380544   ERROR: GetDirectory - Error getting /mnt/storage/lost+found/
23:03:03 T:140008146835360   ERROR: Previous line repeats 2 times.
23:03:03 T:140008146835360  NOTICE: DVDPlayer: Opening: /mnt/storage/Movies/American.Psycho.2000.576p.BluRay.x264-XTSF/!sample/!sample.mkv
23:03:03 T:140008146835360 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
23:03:03 T:140007766222592  NOTICE: Creating InputStream
23:03:03 T:140007766222592  NOTICE: Creating Demuxer
23:03:03 T:140007766222592  NOTICE: Opening video stream: 0 source: 256
23:03:03 T:140007766222592  NOTICE: Creating video codec with codec id: 28
23:03:03 T:140007766222592  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
23:03:03 T:140007766222592  NOTICE: Creating video thread
23:03:03 T:140007766222592  NOTICE: Opening audio stream: 1 source: 256
23:03:03 T:140007766222592  NOTICE: Finding audio codec for: 86019
23:03:03 T:140007630251776  NOTICE: running thread: video_thread
23:03:03 T:140007766222592  NOTICE: Creating audio thread
23:03:03 T:140007621859072  NOTICE: running thread: CDVDPlayerAudio::Process()
23:03:03 T:140007621859072  NOTICE: Creating audio device with codec id: 86019, channels: 2, sample rate: 48000, pass-through
23:03:03 T:140007630251776  NOTICE:  fps: 23.976024, pwidth: 1024, pheight: 432, dwidth: 1024, dheight: 432
23:03:03 T:140007630251776  NOTICE: Display resolution DESKTOP : 1920x1080 @ 60.00 - Full Screen (12)
23:03:03 T:140008146835360  NOTICE: Using GL_TEXTURE_2D
23:03:03 T:140008146835360  NOTICE: GL: Using VAAPI render method
23:03:03 T:140008146835360  NOTICE: GL: NPOT texture support detected
23:03:03 T:140008146835360  NOTICE: GL: Using GL_ARB_pixel_buffer_object
23:04:08 T:140007621859072 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
23:04:08 T:140007766222592  NOTICE: CDVDPlayer::OnExit()
23:04:08 T:140007766222592  NOTICE: DVDPlayer: eof, waiting for queues to empty
23:04:08 T:140007766222592  NOTICE: DVDPlayer: closing audio stream
23:04:08 T:140007766222592  NOTICE: Closing audio stream
23:04:08 T:140007766222592  NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty
23:04:08 T:140007766222592  NOTICE: Waiting for audio thread to exit
23:04:08 T:140007621859072  NOTICE: thread end: CDVDPlayerAudio::OnExit()
23:04:08 T:140007766222592  NOTICE: Closing audio device
23:04:08 T:140007766222592  NOTICE: Deleting audio codec
23:04:08 T:140007766222592  NOTICE: DVDPlayer: closing video stream
23:04:08 T:140007766222592  NOTICE: Closing video stream
23:04:08 T:140007766222592  NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty
23:04:08 T:140007630251776 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
23:04:08 T:140007766222592  NOTICE: waiting for video thread to exit
23:04:08 T:140007630251776   ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
23:04:08 T:140007630251776  NOTICE: thread end: video_thread
23:04:08 T:140007766222592  NOTICE: deleting video codec
23:04:08 T:140007766222592  NOTICE: CDVDPlayer::OnExit() deleting demuxer
23:04:08 T:140007766222592  NOTICE: CDVDPlayer::OnExit() deleting input stream
23:04:08 T:140008146835360  NOTICE: CDVDPlayer::CloseFile()
23:04:08 T:140008146835360 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
23:04:08 T:140008146835360  NOTICE: DVDPlayer: waiting for threads to exit
23:04:08 T:140008146835360  NOTICE: DVDPlayer: finished waiting

22:44:18 T:140653116098464  NOTICE: -----------------------------------------------------------------------
22:44:19 T:140653116098464  NOTICE: Starting XBMC (11.0 Git:Unknown), Platform: Linux (Debian GNU/Linux testing (wheezy), 3.6.0-rc1+ x86_64). Built on Jun 15 2012
22:44:19 T:140653116098464  NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc
22:44:19 T:140653116098464  NOTICE: special://xbmcbin/ is mapped to: /usr/lib/xbmc
22:44:19 T:140653116098464  NOTICE: special://masterprofile/ is mapped to: /home/mhoran/.xbmc/userdata
22:44:19 T:140653116098464  NOTICE: special://home/ is mapped to: /home/mhoran/.xbmc
22:44:19 T:140653116098464  NOTICE: special://temp/ is mapped to: /home/mhoran/.xbmc/temp
22:44:19 T:140653116098464  NOTICE: The executable running is: /usr/lib/xbmc/xbmc.bin
22:44:19 T:140653116098464  NOTICE: Local hostname: challenger
22:44:19 T:140653116098464  NOTICE: Log File is located: /home/mhoran/.xbmc/temp/xbmc.log
22:44:19 T:140653116098464  NOTICE: -----------------------------------------------------------------------
22:44:19 T:140653116098464  NOTICE: Setup SDL
22:44:19 T:140653116098464  NOTICE: load settings...
22:44:19 T:140653116098464  NOTICE: special://profile/ is mapped to: special://masterprofile/
22:44:19 T:140653116098464  NOTICE: loading special://masterprofile/guisettings.xml
22:44:19 T:140653116098464  NOTICE: Getting hardware information now...
22:44:19 T:140653116098464  NOTICE: Checking resolution 12
22:44:19 T:140653116098464  NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
22:44:19 T:140653116098464  NOTICE: Loaded playercorefactory configuration
22:44:19 T:140653116098464  NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
22:44:19 T:140653116098464  NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
22:44:19 T:140653116098464  NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml)
22:44:19 T:140653116098464  NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
22:44:19 T:140653116098464  NOTICE: Default DVD Player: dvdplayer
22:44:19 T:140653116098464  NOTICE: Default Video Player: dvdplayer
22:44:19 T:140653116098464  NOTICE: Default Audio Player: paplayer
22:44:19 T:140653116098464  NOTICE: Disabled debug logging due to GUI setting. Level 0.
22:44:19 T:140653116098464  NOTICE: Log level changed to 0
22:44:19 T:140653116098464  NOTICE: Loading media sources from special://masterprofile/sources.xml
22:44:20 T:140653116098464  NOTICE: Using visual 0x22
22:44:20 T:140653116098464  NOTICE: GL_VENDOR = Tungsten Graphics, Inc
22:44:20 T:140653116098464  NOTICE: GL_RENDERER = Mesa DRI Intel(R) Sandybridge Desktop
22:44:20 T:140653116098464  NOTICE: GL_VERSION = 3.0 Mesa 8.0.4
22:44:20 T:140653116098464  NOTICE: GL_SHADING_LANGUAGE_VERSION = 1.30
22:44:20 T:140653116098464  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_SUN_multi_draw_arrays GL_ARB_texture_border_clamp GL_ARB_texture_compression GL_EXT_framebuffer_object GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_MESA_window_pos GL_NV_packed_depth_stencil GL_NV_texture_rectangle GL_NV_vertex_program 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_NV_vertex_program1_1 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_ARB_depth_clamp GL_ARB_fragment_program_shadow GL_ARB_half_float_pixel 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_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_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_half_float_vertex 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_ARB_ES2_compatibility 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_EXT_provoking_vertex GL_EXT_texture_snorm GL_MESA_texture_signed_rgba GL_ARB_robustness GL_EXT_transform_feedback
22:44:22 T:140653116098464   ERROR: GLX: Same window as before, refreshing context
22:44:22 T:140653116098464  NOTICE: UDisks: Added /mnt/storage
22:44:22 T:140653116098464  NOTICE: start dvd mediatype detection
22:44:22 T:140653116098464  NOTICE: initializing playlistplayer
22:44:22 T:140653116098464  NOTICE: DONE initializing playlistplayer
22:44:23 T:140653116098464  NOTICE: initialize done
22:44:23 T:140653116098464  NOTICE: Running the application...
22:44:23 T:140653116098464  NOTICE: ES: Starting event server
22:44:23 T:140652847290112  NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
22:44:23 T:140652847290112  NOTICE: UDP: Listening on port 9777
22:44:23 T:140653116098464  NOTICE: starting zeroconf publishing
22:44:23 T:140652956313344  NOTICE: -->Python Interpreter Initialized<--
22:44:23 T:140652947920640  NOTICE: -->Python Interpreter Initialized<--
22:44:23 T:140652956313344  NOTICE: Artwork Downloader: ## Service - Run at startup: False
22:44:23 T:140652956313344  NOTICE: Artwork Downloader: ## Service - Delayed startup: -- minutes
22:44:23 T:140652956313344  NOTICE: Artwork Downloader: ## Service - Run as service: False
22:44:23 T:140652956313344  NOTICE: Artwork Downloader: ## Service - Time: 00:00
22:44:23 T:140652809049856  NOTICE: -->Python Interpreter Initialized<--
22:44:23 T:140652947920640  NOTICE:  StorageServer Module loaded RUN
22:44:23 T:140652947920640  NOTICE: StorageClient-1.1.0 Starting server
22:44:23 T:140652947920640  NOTICE: StorageServer-1.1.0 Storage Server starting /home/mhoran/.xbmc/userdata/Database/commoncache.db
22:44:45 T:140652809049856   ERROR: GetDirectory - Error getting /mnt/storage/lost+found/
22:44:50 T:140653116098464  NOTICE: DVDPlayer: Opening: /mnt/storage/Movies/American.Psycho.2000.576p.BluRay.x264-XTSF/!sample/!sample.mkv
22:44:50 T:140653116098464 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
22:44:50 T:140652964706048  NOTICE: Creating InputStream
22:44:50 T:140652964706048  NOTICE: Creating Demuxer
22:44:51 T:140652964706048  NOTICE: Opening video stream: 0 source: 256
22:44:51 T:140652964706048  NOTICE: Creating video codec with codec id: 28
22:44:51 T:140652964706048  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
22:44:51 T:140652964706048  NOTICE: Creating video thread
22:44:51 T:140652964706048  NOTICE: Opening audio stream: 1 source: 256
22:44:51 T:140653006640896  NOTICE: running thread: video_thread
22:44:51 T:140652964706048  NOTICE: Finding audio codec for: 86019
22:44:51 T:140652964706048  NOTICE: Creating audio thread
22:44:51 T:140652998248192  NOTICE: running thread: CDVDPlayerAudio::Process()
22:44:51 T:140652998248192  NOTICE: Creating audio device with codec id: 86019, channels: 2, sample rate: 48000, pass-through
22:44:51 T:140653006640896  NOTICE:  fps: 23.976024, pwidth: 1024, pheight: 432, dwidth: 1024, dheight: 432
22:44:51 T:140653006640896  NOTICE: Display resolution DESKTOP : 1920x1080 @ 60.00 - Full Screen (12)
22:44:51 T:140652998248192   ERROR: PulseAudio: Waited for the stream but it failed
22:44:51 T:140652998248192   ERROR: Creating a Null Audio Renderer, Check your audio settings as this should not happen
22:44:51 T:140653116098464  NOTICE: Using GL_TEXTURE_2D
22:44:51 T:140653116098464  NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
22:44:51 T:140653116098464  NOTICE: GL: NPOT texture support detected
22:44:51 T:140653116098464  NOTICE: GL: Using GL_ARB_pixel_buffer_object
22:44:59 T:140653116098464  NOTICE: CDVDPlayer::CloseFile()
22:44:59 T:140653116098464  NOTICE: DVDPlayer: waiting for threads to exit
22:44:59 T:140652964706048  NOTICE: CDVDPlayer::OnExit()
22:44:59 T:140652964706048  NOTICE: DVDPlayer: closing audio stream
22:44:59 T:140652964706048  NOTICE: Closing audio stream
22:44:59 T:140652964706048  NOTICE: Waiting for audio thread to exit
22:44:59 T:140652998248192  NOTICE: thread end: CDVDPlayerAudio::OnExit()
22:44:59 T:140652964706048  NOTICE: Closing audio device
22:44:59 T:140652964706048  NOTICE: Deleting audio codec
22:44:59 T:140652964706048  NOTICE: DVDPlayer: closing video stream
22:44:59 T:140652964706048  NOTICE: Closing video stream
22:44:59 T:140652964706048  NOTICE: waiting for video thread to exit
22:44:59 T:140653006640896  NOTICE: thread end: video_thread
22:44:59 T:140652964706048  NOTICE: deleting video codec
22:44:59 T:140652964706048  NOTICE: CDVDPlayer::OnExit() deleting demuxer
22:44:59 T:140652964706048  NOTICE: CDVDPlayer::OnExit() deleting input stream
22:44:59 T:140653116098464  NOTICE: DVDPlayer: finished waiting
22:45:00 T:140653116098464  NOTICE: CDVDPlayer::CloseFile()
22:45:00 T:140653116098464 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
22:45:00 T:140653116098464  NOTICE: DVDPlayer: waiting for threads to exit
22:45:00 T:140653116098464  NOTICE: DVDPlayer: finished waiting
22:45:11 T:140653116098464  NOTICE: DVDPlayer: Opening: /mnt/storage/Movies/American.Psycho.2000.576p.BluRay.x264-XTSF/!sample/!sample.mkv
22:45:11 T:140653116098464 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
22:45:11 T:140652998248192  NOTICE: Creating InputStream
22:45:11 T:140652998248192  NOTICE: Creating Demuxer
22:45:11 T:140652998248192  NOTICE: Opening video stream: 0 source: 256
22:45:11 T:140652998248192  NOTICE: Creating video codec with codec id: 28
22:45:11 T:140652998248192  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
22:45:11 T:140652998248192  NOTICE: Creating video thread
22:45:11 T:140652998248192  NOTICE: Opening audio stream: 1 source: 256
22:45:11 T:140652989855488  NOTICE: running thread: video_thread
22:45:11 T:140652998248192  NOTICE: Finding audio codec for: 86019
22:45:11 T:140652998248192  NOTICE: Creating audio thread
22:45:11 T:140652981462784  NOTICE: running thread: CDVDPlayerAudio::Process()
22:45:11 T:140652981462784  NOTICE: Creating audio device with codec id: 86019, channels: 2, sample rate: 48000, pass-through
22:45:11 T:140652989855488  NOTICE:  fps: 23.976024, pwidth: 1024, pheight: 432, dwidth: 1024, dheight: 432
22:45:11 T:140652989855488 WARNING: CRenderManager::Configure - timeout waiting for previous frame
22:45:11 T:140652989855488  NOTICE: Display resolution DESKTOP : 1920x1080 @ 60.00 - Full Screen (12)
22:45:11 T:140653116098464  NOTICE: Using GL_TEXTURE_2D
22:45:11 T:140653116098464  NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
22:45:11 T:140653116098464  NOTICE: GL: NPOT texture support detected
22:45:11 T:140653116098464  NOTICE: GL: Using GL_ARB_pixel_buffer_object
22:45:17 T:140653116098464  NOTICE: CDVDPlayer::CloseFile()
22:45:17 T:140653116098464  NOTICE: DVDPlayer: waiting for threads to exit
22:45:17 T:140652998248192  NOTICE: CDVDPlayer::OnExit()
22:45:17 T:140652998248192  NOTICE: DVDPlayer: closing audio stream
22:45:17 T:140652998248192  NOTICE: Closing audio stream
22:45:17 T:140652998248192  NOTICE: Waiting for audio thread to exit
22:45:17 T:140652981462784   ERROR: AddPackets - failed to add leftover bytes to render
22:45:17 T:140652981462784  NOTICE: thread end: CDVDPlayerAudio::OnExit()
22:45:17 T:140652998248192  NOTICE: Closing audio device
22:45:17 T:140652998248192  NOTICE: Deleting audio codec
22:45:17 T:140652998248192  NOTICE: DVDPlayer: closing video stream
22:45:17 T:140652998248192  NOTICE: Closing video stream
22:45:17 T:140652998248192  NOTICE: waiting for video thread to exit
22:45:17 T:140652989855488  NOTICE: thread end: video_thread
22:45:17 T:140652998248192  NOTICE: deleting video codec
22:45:17 T:140652998248192  NOTICE: CDVDPlayer::OnExit() deleting demuxer
22:45:17 T:140652998248192  NOTICE: CDVDPlayer::OnExit() deleting input stream
22:45:17 T:140653116098464  NOTICE: DVDPlayer: finished waiting
22:45:17 T:140653116098464  NOTICE: CDVDPlayer::CloseFile()
22:45:17 T:140653116098464 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
22:45:17 T:140653116098464  NOTICE: DVDPlayer: waiting for threads to exit
22:45:17 T:140653116098464  NOTICE: DVDPlayer: finished waiting
22:45:42 T:140652809049856   ERROR: GetDirectory - Error getting /mnt/storage/lost+found/
22:45:49 T:140653116098464  NOTICE: DVDPlayer: Opening: /mnt/storage/Movies/American.Psycho.2000.576p.BluRay.x264-XTSF/!sample/!sample.mkv
22:45:49 T:140653116098464 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
22:45:49 T:140652956313344  NOTICE: Creating InputStream
22:45:49 T:140652956313344  NOTICE: Creating Demuxer
22:45:49 T:140652956313344  NOTICE: Opening video stream: 0 source: 256
22:45:49 T:140652956313344  NOTICE: Creating video codec with codec id: 28
22:45:49 T:140652956313344  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
22:45:49 T:140652956313344  NOTICE: Creating video thread
22:45:49 T:140652956313344  NOTICE: Opening audio stream: 1 source: 256
22:45:49 T:140652724287232  NOTICE: running thread: video_thread
22:45:49 T:140652956313344  NOTICE: Finding audio codec for: 86019
22:45:49 T:140652956313344  NOTICE: Creating audio thread
22:45:49 T:140652705003264  NOTICE: running thread: CDVDPlayerAudio::Process()
22:45:49 T:140652705003264  NOTICE: Creating audio device with codec id: 86019, channels: 2, sample rate: 48000, pass-through
22:45:56 T:140652705003264 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available