TV Show playback causes lock up - fowie - 2008-12-23
I'm running the latest XBMC available via PPA for Hardy. Everything seemed to be working fine but now that I switched my hard drive (mounted via SMB) from NTFS to XFS, XBMC locks up for seemingly no reason. I tried to play a TV show and I hear the audio but the video never comes up and XBMC locks up. SSH'ing into the box shows the CPU using 100% CPU time for xbmc.bin. Here's my log fileI'd REALLY like to post the whole log, but this forum won't let my post be over 10,000 characters long...hope you get the important info)
Code: 20:25:12 T:2789964688 M: 37564416 NOTICE: Opening video stream: 0 source: 256
20:25:12 T:2789964688 M: 37564416 NOTICE: Creating video codec with codec id: 13
20:25:12 T:2789964688 M: 37564416 DEBUG: FactoryCodec - Video: FFmpeg - Opening
20:25:12 T:2789964688 M: 37564416 DEBUG: SECTION:LoadDLL(Q:\system\players\dvdplayer\swscale-51-i486-linux.so)
20:25:12 T:2789964688 M: 37564416 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/swscale-51-i486-linux.so
20:25:12 T:2789964688 M: 37191680 DEBUG: FactoryCodec - Video: FFmpeg - Opened
20:25:12 T:2789964688 M: 37191680 NOTICE: Creating video thread
20:25:12 T:2764786576 M: 37191680 DEBUG: Running thread 2764786576
20:25:12 T:2764786576 M: 37191680 DEBUG: thread start, auto delete: 0
20:25:12 T:2764786576 M: 37191680 NOTICE: running thread: video_thread
20:25:12 T:2764786576 M: 37191680 DEBUG: thread start, auto delete: 0
20:25:12 T:2764786576 M: 37191680 NOTICE: running thread: video_thread
20:25:12 T:2789964688 M: 37191680 NOTICE: Opening audio stream: 1 source: 256
20:25:12 T:2789964688 M: 37191680 NOTICE: Finding audio codec for: 86020
20:25:12 T:2789964688 M: 37191680 DEBUG: FactoryCodec - Audio: passthrough - Opening
20:25:12 T:2789964688 M: 37191680 DEBUG: FactoryCodec - Audio: passthrough - Failed
20:25:12 T:2789964688 M: 37191680 DEBUG: FactoryCodec - Audio: liba52 - Opening
20:25:12 T:2789964688 M: 37191680 DEBUG: SECTION:LoadDLL(Q:\system\players\dvdplayer\liba52-i486-linux.so)
20:25:12 T:2789964688 M: 37191680 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/liba52-i486-linux.so
20:25:12 T:2789964688 M: 36925440 DEBUG: FactoryCodec - Audio: liba52 - Opened
20:25:12 T:2789964688 M: 36925440 NOTICE: Creating audio thread
20:25:12 T:2756393872 M: 36925440 DEBUG: Running thread 2756393872
20:25:12 T:2756393872 M: 36925440 DEBUG: thread start, auto delete: 0
20:25:12 T:2756393872 M: 36925440 NOTICE: running thread: CDVDPlayerAudio::Process()
20:25:12 T:2789964688 M: 36925440 DEBUG: Playback has started
20:25:12 T:2789964688 M: 36925440 INFO: Loading skin file: DialogProgress.xml
20:25:12 T:2789964688 M: 36892672 DEBUG: Load DialogProgress.xml: 18.66ms
20:25:12 T:2789964688 M: 36892672 WARNING: CDVDPlayer::CheckContinuity - stream wrapback detected (2)
20:25:12 T:2789964688 M: 36892672 WARNING: CDVDPlayer::CheckContinuity - resyncing due to stream wrapback (2)
20:25:12 T:3067516768 M: 36143104 DEBUG: ------------------- GUI_MSG_WINDOW_INIT
20:25:12 T:3067516768 M: 36143104 DEBUG:
20:25:12 T:3067516768 M: 36143104 DEBUG: -------------------
20:25:12 T:3067516768 M: 36143104 DEBUG: Load seekslider.png: 0.0ms (bundled)
20:25:12 T:3067516768 M: 36143104 DEBUG: Load slider_nibFO.png: 0.0ms (bundled)
20:25:12 T:3067516768 M: 36143104 DEBUG: Alloc resources: 0.51ms (0.00 ms skin load, 0.20 ms preload)
20:25:12 T:3067516768 M: 35610624 DEBUG: Load black-back2.png: 16.2ms (bundled)
20:25:12 T:3067516768 M: 35557376 DEBUG: Load NoCover_1.png: 0.9ms (bundled)
20:25:12 T:3067516768 M: 35557376 DEBUG: Load NoCover_1.png: 0.9ms (bundled)
20:25:12 T:3067516768 M: 35557376 DEBUG: Load black-back.png: 0.1ms (bundled)
20:25:12 T:3067516768 M: 35553280 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://FOWIE-DESKTOP/TVSeries/Seinfeld/The_Big_S\
alad_s06e02.avi)
20:25:12 T:3067516768 M: 35553280 ERROR: XLCDproc::SetBackLight - Unable to write to socket
20:25:12 T:2756393872 M: 26906624 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(33375.000000, 1)
20:25:12 T:2756393872 M: 26906624 NOTICE: Creating audio device with codec id: 86020, channels: 2, sample rate: 48000, no \
pass-through
20:25:12 T:2756393872 M: 26906624 DEBUG: CALSADirectSound::CALSADirectSound - opening alsa device
20:25:12 T:2764786576 M: 26906624 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
20:25:12 T:2764786576 M: 25575424 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(66733.333333, 1)
20:25:12 T:2764786576 M: 24936448 NOTICE: fps: 29.970030, pwidth: 720, pheight: 480, dwidth: 720, dheight: 480
20:25:12 T:2764786576 M: 25575424 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(66733.333333, 1)
20:25:12 T:2764786576 M: 24936448 NOTICE: fps: 29.970030, pwidth: 720, pheight: 480, dwidth: 720, dheight: 480
20:25:12 T:2764786576 M: 24936448 DEBUG: OutputPicture - change configuration. 720x480. framerate: 29.97
20:25:12 T:2764786576 M: 24883200 NOTICE: Display resolution AUTO : 1360x768 (Full Screen) (12)
20:25:12 T:2764786576 M: 24776704 WARNING: Upscale: selected algorithm 1
20:25:12 T:2798357392 M: 24403968 DEBUG: staticThread, deleting thread object
20:25:12 T:2798357392 M: 24350720 DEBUG: staticThread, deleting thread graphic context
20:25:12 T:2798357392 M: 24350720 DEBUG: Thread 2798357392 terminating
20:25:12 T:2756393872 M: 23519232 DEBUG: CALSADirectSound - using alsa device default
20:25:12 T:2756393872 M: 23519232 DEBUG: CDVDPlayerAudio:: Discontinuty - was:113723.219333, should be:33375.000000, erro\
r:-80348.219333
20:25:13 T:2756393872 M: 4857856 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_DELAY(958641.666667)
20:25:14 T:2756393872 M: 4857856 ERROR: GetDelay - snd_pcm_delay, alsa error: Broken pipe
20:25:14 T:2756393872 M: 5070848 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(1025375.000000, 0)
20:25:14 T:2756393872 M: 5070848 DEBUG: CDVDPlayerAudio:: Discontinuty - was:1640850.716000, should be:1025375.000000, e\
rror:-615475.716000
20:25:14 T:2756393872 M: 32333824 DEBUG: CDVDPlayerAudio:: Discontinuty - was:1387767.333000, should be:1376053.367656, e\
rror:-11713.965344
20:25:22 T:2756393872 M: 7196672 DEBUG: CALSADirectSound::AddPackets - buffer underun (tried to write 1024 frames)
20:25:22 T:2756393872 M: 7196672 DEBUG: CDVDPlayerAudio:: Discontinuty - was:8994269.406656, should be:8705375.000000, e\
rror:-288894.406656
etc....
- althekiller - 2008-12-23
Use pastebin.com for the full log and see the first link in my signature for other info we need.
- fowie - 2008-12-31
Ok, here goes:
http://pastebin.com/f66126472
Versions:
Mythbuntu 8.10
Linux fowie-mc 2.6.24-22-generic #1 SMP Mon Nov 24 18:32:42 UTC 2008 i686 GNU/Linux
XBMC Atlantis (From Hardy PPAs, up to date)
How to reproduce:
On my computer, load XBMC (via command line over VNC, or using the auto-started process on boot), go to Videos > TV Shows > Seinfeld, pick any episode (including ones I have watched previously in XBMC) and select OK on the MCE remote. The progress meter will display in the upper right corner, but the display will not go to the video. Audio begins playing in the background until the xbmc process is killed.
Video codec: xvid
Audio Codec: copy
Encoder command (example): mencoder /tmp//Bee_Movie-cache -oac copy -aid 128 -ovc xvid -xvidencopts bitrate=2500:chroma_opt:vhq=4:bvhq=1:quant_type=mpeg -vf pp=de -aid 128 -o "/DVDs/DVDs/B/Bee_Movie.avi"
If I run XBMC from the command line and follow the steps on how to reproduce this error, after a while the audio starts to glitch (no video ever plays) and finally xbmc crashes back to the command line and this is what spews out on the command line:
Code: fowie@fowie-mc:~$ xbmc
********ERROR- exception caught on main loop. exitingCRITSEC[0x8bcde24]: Trying to enter destroyed section.
CRITSEC[0x8bcde24]: Trying to enter destroyed section.
CRITSEC[0x8bcde24]: Trying to enter destroyed section.
CRITSEC[0x8bcdeb0]: Trying to enter destroyed section.
X Error of failed request: BadDrawable (invalid Pixmap or Window parameter)
Major opcode of failed request: 146 (XFree86-DRI)
Minor opcode of failed request: 9 ()
Resource id in failed request: 0x2e00002
Serial number of failed request: 1363
Current serial number in output stream: 1363
CRITSEC[0x8bcde24]: Trying to enter destroyed section.
CRITSEC[0x8bcde24]: Trying to leave destroyed section.
CRITSEC[0x8bcf320]: Trying to enter destroyed section.
CRITSEC[0x8bcf320]: Trying to leave destroyed section.
CRITSEC[0x8bcf320]: Trying to enter destroyed section.
CRITSEC[0x8bcf320]: Trying to leave destroyed section.
CRITSEC[0x8bcf320]: Trying to enter destroyed section.
CRITSEC[0x8bcf320]: Trying to leave destroyed section.
CRITSEC[0x8bda73c]: Trying to enter destroyed section.
fowie@fowie-mc:~$ export DISPLAY=:0
fowie@fowie-mc:~$ xbmc
********ERROR- exception caught on main loop. exitingCRITSEC[0x8bcde24]: Trying to enter destroyed section.
CRITSEC[0x8bcde24]: Trying to enter destroyed section.
CRITSEC[0x8bcde24]: Trying to enter destroyed section.
CRITSEC[0x8bcdeb0]: Trying to enter destroyed section.
X Error of failed request: BadDrawable (invalid Pixmap or Window parameter)
Major opcode of failed request: 146 (XFree86-DRI)
Minor opcode of failed request: 9 ()
Resource id in failed request: 0x3400002
Serial number of failed request: 1102
Current serial number in output stream: 1102
CRITSEC[0x8bcde24]: Trying to enter destroyed section.
CRITSEC[0x8bcde24]: Trying to leave destroyed section.
CRITSEC[0x8bcf320]: Trying to enter destroyed section.
CRITSEC[0x8bcf320]: Trying to leave destroyed section.
CRITSEC[0x8bcf320]: Trying to enter destroyed section.
CRITSEC[0x8bcf320]: Trying to leave destroyed section.
CRITSEC[0x8bcf320]: Trying to enter destroyed section.
CRITSEC[0x8bcf320]: Trying to leave destroyed section.
CRITSEC[0x8bda73c]: Trying to enter destroyed section.
Segmentation fault (core dumped)
fowie@fowie-mc:~$
--EDIT--
I restarted XBMC again (by using VNC and executing it via a terminal window on the command line, just and it is playing. I'd like to fix whatever is causing this intermittent problem. Here is the debug output of the player working correctly:
http://pastebin.com/f28a154b2
- fowie - 2009-01-06
-Update-
I'm not sure if this has fixed the issue or not, I'll keep you posted, but it seems to work at least a little more reliably if I make sure that I run
before I start up XBMC. Would that make sense why XBMC would display but video would not?
- tslayer - 2009-01-06
If you get a core dump, run gdb on it and get a backtrace.
- kjekjo - 2009-01-13
Hi,
I had almost the exact same problem, XBMC froze up whenever I tried to stop a running video with the stop button and it would quit to the Desktop. The problem was not only the stop button, but the stop button was the only one which was consistent.
Same kind of errors as the starter of this thread. I tried the export theory of his and it seems to work, only I had to use export DISPLAY=:1 as I run XBMC on a dedicated laptop which is located under my TV.
Thanks a lot Fowie!
|