Linux - crash when playing content with VDPAU enabled

  Thread Rating:
  • 1 Votes - 5 Average
  • 1
  • 2
  • 3
  • 4
  • 5
Post Reply
basco Offline
Senior Member
Posts: 221
Joined: Jun 2010
Reputation: 1
Post: #1
I have 4 ubuntu 11.04 xbmc boxes at home.
They all have the same symptoms since Eden.
When playing a stream XBMC crashes.

The 4 boxes have different hardware except that they all have a G210 graphics card.
When i disable VDPAU the problem goes away (that is not an option of course...)

Doen anyone know what to to about this? BIG THANKS!!

20:35:06 T:2952780656 NOTICE: vdp_device = 0x00000001 vdp_st = 0x00000000
20:35:06 T:2952780656 NOTICE: VDPAU Decoder capabilities:
20:35:06 T:2952780656 NOTICE: name level macbs width height
20:35:06 T:2952780656 NOTICE: ------------------------------------
20:35:06 T:2952780656 NOTICE: MPEG1 0 8192 2048 2048
20:35:06 T:2952780656 NOTICE: MPEG2_SIMPLE 3 8192 2048 2048
20:35:06 T:2952780656 NOTICE: MPEG2_MAIN 3 8192 2048 2048
20:35:06 T:2952780656 NOTICE: H264_MAIN 41 8192 2048 2048
20:35:06 T:2952780656 NOTICE: H264_HIGH 41 8192 2048 2048
20:35:06 T:2952780656 NOTICE: VC1_SIMPLE 1 8190 2048 2048
20:35:06 T:2952780656 NOTICE: VC1_MAIN 2 8190 2048 2048
20:35:06 T:2952780656 NOTICE: VC1_ADVANCED 4 8190 2048 2048
20:35:06 T:2952780656 NOTICE: MPEG4_PART2_ASP 5 8192 2048 2048
20:35:06 T:2952780656 NOTICE: ------------------------------------
20:35:06 T:2952780656 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_NOISE_REDUCTION
20:35:06 T:2952780656 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_SHARPNESS
20:35:06 T:2952780656 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL
20:35:06 T:2952780656 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL_SPATIAL
20:35:06 T:2952780656 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_INVERSE_TELECINE
20:35:06 T:2952780656 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_HIGH_QUALITY_SCALING_L1
20:35:06 T:2952780656 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 (VDPAU acceleration)
20:35:06 T:2952780656 DEBUG: FactoryCodec - Video: ff-h264_vdpau-vdpau - Opened
20:35:06 T:2952780656 NOTICE: Creating video thread
20:35:06 T:3032480624 DEBUG: Thread CDVDPlayerVideo start, auto delete: 0
20:35:06 T:2952780656 DEBUG: CDVDPlayer::HandleMessages - player started 1
20:35:06 T:3032480624 NOTICE: running thread: video_thread
20:35:06 T:3032480624 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(3292840066.000000, 0)
20:35:06 T:3032480624 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
20:35:06 T:3032480624 ERROR: ffmpeg[B4BFFB70]: [h264_vdpau] mmco: unref short failure
20:35:06 T:3032480624 NOTICE: (VDPAU) screenWidth:0 vidWidth:1920 surfaceWidth:1920
20:35:06 T:3032480624 NOTICE: (VDPAU) screenHeight:0 vidHeight:1080 surfaceHeight:1088
20:35:06 T:2952780656 DEBUG: set caching from pvr to done. audio (1) = 6. video (1) = 6
20:35:06 T:2952780656 DEBUG: CDVDPlayer::SetCaching - caching state 0
20:35:06 T:2952780656 ERROR: Get - failed to get stream
20:35:06 T:2755189616 DEBUG: CDVDPlayerAudio:: Discontinuity - was:3291636920.085000, should be:3291636635.089960, error:-284.995040
20:35:07 T:2755189616 DEBUG: CDVDPlayerAudio:: Discontinuity - was:3292645716.312960, should be:3292665716.312960, error:20000.000000
20:35:14 T:2755189616 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
20:35:14 T:2755189616 ERROR: GetDelay - snd_pcm_delay, alsa error: -32 - Broken pipe
(This post was last modified: 2012-05-10 15:01 by gnif.)
find quote
eluSive_NZ Offline
Junior Member
Posts: 29
Joined: Jan 2010
Reputation: 0
Location: New Zealand
Post: #2
Did you manage to fix this issue, as I have exactly the same error. Interestingly, I've also tried the latest beta OpenElec and the same issue occurs.
find quote
gmakki Offline
Junior Member
Posts: 4
Joined: May 2012
Reputation: 1
Post: #3
Hi,

I get the same errors in the debug log before XBMC freezes. In fact I had been having this already with previous XBMC releases, so this is not an Eden problem for me...
The problem occurs quite randomly, at least I could so far not detect any pattern or relationship with the movie or anything else...

Here are the symptoms (running on the official XBMCBuntu Eden on nVidia GeForce9400 GPU, with the 295.33 driver):

1) Select a movie
2) Press Play
3) The "Working" hourglass appears in the corner
4) The screen goes sort of half-way dim
5) The timer starts ticking away
6) Sometimes the first few seconds of the audio play, but no video
7) Key presses are still being detected (according to the debug log)
8) Pressing Stop will stop the counter, but I never get returned to the menu
9) XBMC frozen...

Here is an excerpt from the debug log, but as said, pretty much the same as basco has above, with the Broken pipe on GetDelay / snd_pcm_delay...

10:12:26 T:6273216 DEBUG: LoadVideoInfo, got movie info!
10:12:26 T:6273216 DEBUG: Title = Inception
10:12:26 T:6273216 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
10:12:26 T:6273216 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
10:12:26 T:2916866928 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(42000.000000, 0)
10:12:26 T:2916866928 NOTICE: Creating 1280x534 pixmap
10:12:26 T:2916866928 DEBUG: Found 4 fbconfigs.
10:12:26 T:2916866928 DEBUG: Using fbconfig index 0.
10:12:26 T:6273216 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
10:12:32 T:2962226032 DEBUG: CDVDPlayer::SetCaching - caching state 0
10:12:32 T:2908474224 DEBUG: CDVDPlayerAudio:: Discontinuity - was:445.556000, should be:0.000000, error:-445.556000
10:12:32 T:2908474224 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(10000.000000, 1)
10:12:39 T:3042954096 DEBUG: Compress - using DXT1 (min error is: 23.33:0.00)
10:12:39 T:3042954096 DEBUG: Creating DDS version of: special://masterprofile/Thumbnails/Video/7/758350d0.tbn
10:12:40 T:2908474224 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
10:12:40 T:2908474224 ERROR: GetDelay - snd_pcm_delay, alsa error: -32 - Broken pipe

Then, this is what happens when trying to Stop:

10:14:57 T:6273216 DEBUG: SDLKeyboard: scancode: 88, sym: 00b2, unicode: 0000, modifier: 0
10:14:57 T:6273216 DEBUG: OnKey: stop (f0bc) pressed, action is Stop
10:14:57 T:6273216 NOTICE: CDVDPlayer::CloseFile()
10:14:57 T:6273216 NOTICE: DVDPlayer: waiting for threads to exit
10:14:57 T:2962226032 NOTICE: CDVDPlayer::OnExit()
10:14:57 T:2962226032 NOTICE: DVDPlayer: closing audio stream
10:14:57 T:2962226032 NOTICE: Closing audio stream
10:14:57 T:2962226032 NOTICE: Waiting for audio thread to exit
10:14:57 T:2908474224 DEBUG: CDVDPlayerAudio::Process - Abort received, exiting thread
10:14:57 T:2908474224 NOTICE: thread end: CDVDPlayerAudio::OnExit()
10:14:57 T:2908474224 DEBUG: Thread CDVDPlayerAudio 2908474224 terminating
10:14:57 T:2962226032 NOTICE: Closing audio device
10:14:57 T:2962226032 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1
10:14:57 T:2962226032 DEBUG: RemoveActiveDevice - Removing device 2
10:14:57 T:2962226032 NOTICE: Deleting audio codec
10:14:57 T:2962226032 NOTICE: DVDPlayer: closing video stream
10:14:57 T:2962226032 NOTICE: Closing video stream
10:14:57 T:2962226032 NOTICE: waiting for video thread to exit

Is this is enough input to figure whether the freeze is related to VDPAU?

Many Thanks!
--G
(This post was last modified: 2012-05-13 22:51 by gmakki.)
find quote
rpj999 Offline
Junior Member
Posts: 1
Joined: May 2012
Reputation: 0
Post: #4
I'm also running the official XBMCBuntu Eden on an nVidia GeForce9400 GPU and am experiencing identical errors as gmakki outlined. The steps are identical and, likewise, I'm also unable to detect a pattern of what causes it.

Hope someone will be able to provide some insight/guidance....

R
(This post was last modified: 2012-05-13 23:04 by rpj999.)
find quote
FernetMenta Offline
Team-XBMC Developer
Posts: 3,039
Joined: Jul 2010
Reputation: 60
Location: Munich
Post: #5
Looks like the video thread hangs in a GLX call which is needed to setup interoperability between vdpau and OpenGL. This should not happen but might occur in a combination of XBMC, NVidia driver and Linux kernel. Users with similar problems have reported improvement after they have changed NVdia driver, others Linux kernel. BTW: driver 280 is not very stable for multi-threaded gl applications like XBMC. I think 280 is still nvidia-current for Ubuntu 10.10.
Nevertheless I have redesigned our interface to vdpau. A tester with the same problem as stated in this thread reported that the issue was cured. The work is not in master yet but review process is started.
find quote
norms Offline
Senior Member
Posts: 122
Joined: Dec 2011
Reputation: 0
Post: #6
(2012-05-14 20:01)FernetMenta Wrote:  Looks like the video thread hangs in a GLX call which is needed to setup interoperability between vdpau and OpenGL. This should not happen but might occur in a combination of XBMC, NVidia driver and Linux kernel. Users with similar problems have reported improvement after they have changed NVdia driver, others Linux kernel. BTW: driver 280 is not very stable for multi-threaded gl applications like XBMC. I think 280 is still nvidia-current for Ubuntu 10.10.
Nevertheless I have redesigned our interface to vdpau. A tester with the same problem as stated in this thread reported that the issue was cured. The work is not in master yet but review process is started.

Well that's good news then as there seem to be an awful lot of people experiencing this error, me included.
find quote
gmakki Offline
Junior Member
Posts: 4
Joined: May 2012
Reputation: 1
Post: #7
Good to know the bug is recognized and a fix under consideration & review!

You mention driver 280.xx, but I am now running the latest working driver which is 295.33 with that problem,
so I guess the fix is not going to come from a revised nvidia driver alone...

Thanks!
find quote
norms Offline
Senior Member
Posts: 122
Joined: Dec 2011
Reputation: 0
Post: #8
Code:
00:02:04 T:8320784  NOTICE: DVDPlayer: Opening: /home/xbmc/TV Shows/Firefly/Season 1/S01E01 Serenity.mkv
00:02:04 T:8320784 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
00:02:04 T:2952788848  NOTICE: Creating InputStream
00:02:04 T:2952788848  NOTICE: Creating Demuxer
00:02:05 T:2952788848  NOTICE: Opening video stream: 0 source: 256
00:02:05 T:2952788848  NOTICE: Creating video codec with codec id: 28
00:02:05 T:2952788848  NOTICE: CDVDVideoCodecFFmpeg::Open() Creating VDPAU(1280x720, 28)
00:02:05 T:2952788848  NOTICE: vdp_device = 0x00000001 vdp_st = 0x00000000
00:02:05 T:2952788848  NOTICE: VDPAU Decoder capabilities:
00:02:05 T:2952788848  NOTICE: name          level macbs width height
00:02:05 T:2952788848  NOTICE: ------------------------------------
00:02:05 T:2952788848  NOTICE: MPEG1             0  8192  2048  2048
00:02:05 T:2952788848  NOTICE: MPEG2_SIMPLE      3  8192  2048  2048
00:02:05 T:2952788848  NOTICE: MPEG2_MAIN        3  8192  2048  2048
00:02:05 T:2952788848  NOTICE: H264_MAIN        41  8192  2048  2048
00:02:05 T:2952788848  NOTICE: H264_HIGH        41  8192  2048  2048
00:02:05 T:2952788848  NOTICE: VC1_SIMPLE        1  8190  2048  2048
00:02:05 T:2952788848  NOTICE: VC1_MAIN          2  8190  2048  2048
00:02:05 T:2952788848  NOTICE: VC1_ADVANCED      4  8190  2048  2048
00:02:05 T:2952788848  NOTICE: MPEG4_PART2_ASP   5  8192  2048  2048
00:02:05 T:2952788848  NOTICE: ------------------------------------
00:02:05 T:2952788848  NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_NOISE_REDUCTION
00:02:05 T:2952788848  NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_SHARPNESS
00:02:05 T:2952788848  NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL
00:02:05 T:2952788848  NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL_SPATIAL
00:02:05 T:2952788848  NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_INVERSE_TELECINE
00:02:05 T:2952788848  NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_HIGH_QUALITY_SCALING_L1
00:02:05 T:2952788848  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 (VDPAU acceleration)
00:02:05 T:2952788848  NOTICE: Creating video thread
00:02:05 T:2952788848  NOTICE: Opening audio stream: 1 source: 256
00:02:05 T:2952788848  NOTICE: Finding audio codec for: 86020
00:02:05 T:3053448048  NOTICE: running thread: video_thread
00:02:05 T:2952788848  NOTICE: Creating audio thread
00:02:05 T:2952788848  NOTICE: Opening Subtitle stream: 2 source: 256
00:02:05 T:3053448048  NOTICE:  (VDPAU) screenWidth:0 vidWidth:1280 surfaceWidth:1280
00:02:05 T:3053448048  NOTICE:  (VDPAU) screenHeight:0 vidHeight:720 surfaceHeight:720
00:02:05 T:2885233520  NOTICE: running thread: CDVDPlayerAudio::Process()
00:02:05 T:2885233520  NOTICE: Creating audio device with codec id: 86020, channels: 6, sample rate: 48000, no pass-through
00:02:05 T:2885233520   ERROR: PulseAudio: Failed to connect context
00:02:05 T:2885233520   ERROR: PulseAudio: Failed to create context
00:02:05 T:3053448048  NOTICE: Creating 1280x720 pixmap
00:02:06 T:8320784  NOTICE: CDVDPlayer::CloseFile()
00:02:06 T:8320784  NOTICE: DVDPlayer: waiting for threads to exit
00:02:06 T:2952788848  NOTICE: CDVDPlayer::OnExit()
00:02:06 T:2952788848  NOTICE: DVDPlayer: closing audio stream
00:02:06 T:2952788848  NOTICE: Closing audio stream
00:02:06 T:2952788848  NOTICE: Waiting for audio thread to exit
00:02:06 T:2885233520  NOTICE: thread end: CDVDPlayerAudio::OnExit()
00:02:06 T:2952788848  NOTICE: Closing audio device
00:02:06 T:2952788848  NOTICE: Deleting audio codec
00:02:06 T:2952788848  NOTICE: DVDPlayer: closing video stream
00:02:06 T:2952788848  NOTICE: Closing video stream
00:02:06 T:2952788848  NOTICE: waiting for video thread to exit

That's my log of when XBMC stops responding, pretty much the same as everyone else's I think.
find quote
eluSive_NZ Offline
Junior Member
Posts: 29
Joined: Jan 2010
Reputation: 0
Location: New Zealand
Post: #9
Out if interest, what skin are you all using when this happens?
find quote
gmakki Offline
Junior Member
Posts: 4
Joined: May 2012
Reputation: 1
Post: #10
I am using the standard Confluence.
find quote
norms Offline
Senior Member
Posts: 122
Joined: Dec 2011
Reputation: 0
Post: #11
(2012-05-15 02:51)eluSive_NZ Wrote:  Out if interest, what skin are you all using when this happens?

Confluence, PM3HD and Metropolis with all the same results.
find quote
basco Offline
Senior Member
Posts: 221
Joined: Jun 2010
Reputation: 1
Post: #12
I'm using Confluence too.
I also regularly have the scenario gmakki decribes.
I made a panic button on my remote to reboot the set when this happens Smile

@fernetmenta: i can't wait to use this new vdpau interface.

What didn't help for me is installing x-swat or edgers... i got the same error + some additional ones Wink
(This post was last modified: 2012-05-16 08:28 by basco.)
find quote
FernetMenta Offline
Team-XBMC Developer
Posts: 3,039
Joined: Jul 2010
Reputation: 60
Location: Munich
Post: #13
Quote:@fernetmenta: i can't wait to use this new vdpau interface

In this case you can compile from source and report back if it really solves this problem.
http://wiki.xbmc.org/index.php?title=HOW...ian/Ubuntu

Use this repo: git://github.com/FernetMenta/xbmc.git
find quote
thrillerbee Offline
Junior Member
Posts: 49
Joined: May 2011
Reputation: 0
Post: #14
(2012-05-15 20:56)FernetMenta Wrote:  
Quote:@fernetmenta: i can't wait to use this new vdpau interface

In this case you can compile from source and report back if it really solves this problem.
http://wiki.xbmc.org/index.php?title=HOW...ian/Ubuntu

Use this repo: git://github.com/FernetMenta/xbmc.git

This works! No more freezing!

[Image: watched-clearlogo.jpg]
find quote
gmakki Offline
Junior Member
Posts: 4
Joined: May 2012
Reputation: 1
Post: #15
One interesting observation, now that I changed to Aeon Nox, this VDPAU freeze happens a lot more often..
find quote
Post Reply