[LIVE] Lots of errors in xbmc.log when playing HD file
jinxedworld
Junior Member
Posts: 11
#1
Question  2009-11-12, 23:21
Hi!

Kinda new to the forums, NOT to XBMC. Have been using it for years on my old and trustworthy XBOX, but i thought it was about time to start using my HD tv, so I bought an Asrock 330 ION. I decided to go the easy route and throw on XBMCLive and noticed there was an alpha out. I installed it, didn't change a bit and it ran out of the box! Nice work, kudos for that Smile.

BUT: When I run HD-content I get a load of the following errors. Sometimes XBMC crashes and restarts, and sometimes it just goes on and plays the movie. Either way, I'd like to know if I'm doing something wrong, or that this is a known issue and that it can be fixed. (please note, this happens on ALL HD-files).

Any help would be greatly appreciated!

Code:
20:49:58 T:3045059440 M:1677983744    INFO: ffmpeg[B57FEB70]: Input #0, matroska, from '/home/xbmc/Videos/HD-file.BluRay.1080p.DTS.x264.mkv':
20:49:58 T:3045059440 M:1677983744    INFO: ffmpeg[B57FEB70]:   Duration: 02:06:50.27, start: 0.000000, bitrate: N/A
20:49:58 T:3045059440 M:1677983744    INFO: ffmpeg[B57FEB70]:     Stream #0.0(eng): Video: h264, yuv420p, 1920x800, PAR 1:1 DAR 12:5, 23.98 tbr, 1k tbn, 47.95 tbc
20:49:58 T:3045059440 M:1677983744    INFO: ffmpeg[B57FEB70]:     Stream #0.1(eng): Audio: dca, 48000 Hz, 6 channels, s16
20:49:58 T:3045059440 M:1677983744    INFO: ffmpeg[B57FEB70]:     Stream #0.2(eng): Audio: ac3, 48000 Hz, 2 channels, s16
20:49:58 T:3045059440 M:1677983744    INFO: ffmpeg[B57FEB70]:     Stream #0.3(eng): Subtitle: 0x0000
20:49:58 T:3045059440 M:1677983744    INFO: ffmpeg[B57FEB70]:     Stream #0.4(dut): Subtitle: 0x0000
20:49:58 T:3045059440 M:1677983744   DEBUG: FactoryCodec - Video:  - Opening
20:49:58 T:3045059440 M:1677983744   DEBUG: SECTION:LoadDLL(special://xbmc/system/players/dvdplayer/swscale-0.6.1-i486-linux.so)
20:49:58 T:3045059440 M:1677983744   DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/swscale-0.6.1-i486-linux.so
20:49:58 T:3045059440 M:1677754368  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 (VDPAU acceleration)
20:49:58 T:3045059440 M:1677602816   DEBUG: FactoryCodec - Video: ff-h264_vdpau - Opened
20:49:58 T:3045059440 M:1677602816   DEBUG: ExtractThumb - seeking to pos 2536757ms (total: 7610272ms) in /home/xbmc/Videos/HD-file.BluRay.1080p.DTS.x264.mkv
20:49:58 T:3045059440 M:1677602816   DEBUG: SeekTime - seek ended up on time 2534786
20:49:58 T:3045059440 M:1676705792   ERROR: ffmpeg[B57FEB70]: [h264_vdpau] get_buffer() failed (-1 0 0 (nil))
20:49:58 T:3045059440 M:1676705792   ERROR: ffmpeg[B57FEB70]: [h264_vdpau] decode_slice_header error
20:49:58 T:3045059440 M:1676705792   ERROR: ffmpeg[B57FEB70]: [h264_vdpau] no frame!
20:49:58 T:3045059440 M:1676705792   ERROR: Decode - avcodec_decode_video returned failure
20:49:58 T:3045059440 M:1676705792   ERROR: ffmpeg[B57FEB70]: [h264_vdpau] get_buffer() failed (-1 0 0 (nil))
20:49:58 T:3045059440 M:1676705792   ERROR: ffmpeg[B57FEB70]: [h264_vdpau] decode_slice_header error
20:49:58 T:3045059440 M:1676705792   ERROR: ffmpeg[B57FEB70]: [h264_vdpau] no frame!
20:49:58 T:3045059440 M:1676705792   ERROR: Decode - avcodec_decode_video returned failure
20:49:58 T:3045059440 M:1676705792   ERROR: ffmpeg[B57FEB70]: [h264_vdpau] B picture before any references, skipping
20:49:58 T:3045059440 M:1676705792   ERROR: ffmpeg[B57FEB70]: [h264_vdpau] decode_slice_header error
20:49:58 T:3045059440 M:1676705792   ERROR: ffmpeg[B57FEB70]: [h264_vdpau] no frame!
20:49:58 T:3045059440 M:1676705792   ERROR: Decode - avcodec_decode_video returned failure
20:49:58 T:3045059440 M:1676705792   ERROR: ffmpeg[B57FEB70]: [h264_vdpau] B picture before any references, skipping
20:49:58 T:3045059440 M:1676705792   ERROR: ffmpeg[B57FEB70]: [h264_vdpau] decode_slice_header error
20:49:58 T:3045059440 M:1676705792   ERROR: ffmpeg[B57FEB70]: [h264_vdpau] no frame!
20:49:58 T:3045059440 M:1676705792   ERROR: Decode - avcodec_decode_video returned failure
Find
Reply
abusharif
Member
Posts: 78
#2
2009-11-12, 23:33
exactly same issue here. ION Asrock N330 with latest alpha. Strange thing is worked fine few days ago. Seems to crash every 10 min or so. Tested with several different HD movies.

Code:
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] no frame!
22:33:28 T:2982128528 M:1389056000   ERROR: Decode - avcodec_decode_video returned failure
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] B picture before any references, skipping
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] decode_slice_header error
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] no frame!
22:33:28 T:2982128528 M:1389056000   ERROR: Decode - avcodec_decode_video returned failure
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] B picture before any references, skipping
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] decode_slice_header error
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] no frame!
22:33:28 T:2982128528 M:1389056000   ERROR: Decode - avcodec_decode_video returned failure
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] get_buffer() failed (-1 0 0 (nil))
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] decode_slice_header error
22:33:28 T:2982128528 M:1388953600   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] no frame!
22:33:28 T:2982128528 M:1388953600   ERROR: Decode - avcodec_decode_video returned failure
22:33:28 T:2982128528 M:1388953600   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] B picture before any references, skipping
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] decode_slice_header error
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] no frame!
22:33:28 T:2982128528 M:1389056000   ERROR: Decode - avcodec_decode_video returned failure
22:33:28 T:2982128528 M:1388953600   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] B picture before any references, skipping
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] decode_slice_header error
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] no frame!
22:33:28 T:2982128528 M:1389056000   ERROR: Decode - avcodec_decode_video returned failure
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] B picture before any references, skipping
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] decode_slice_header error
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] no frame!
22:33:28 T:2982128528 M:1389056000   ERROR: Decode - avcodec_decode_video returned failure
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] get_buffer() failed (-1 0 0 (nil))
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] decode_slice_header error
22:33:28 T:2982128528 M:1389056000   ERROR: ffmpeg[B1BFAB90]: [h264_vdpau] no frame!
22:33:28 T:2982128528 M:1389056000   ERROR: Decode - avcodec_decode_video returned failure
Find
Reply
jinxedworld
Junior Member
Posts: 11
#3
2009-11-12, 23:36
Exactly, the only change I made this evening was switching the VGA-cable for a HDMI cable. So the output also changed. But, to be sure the issue wasn't with the video outputs i switched back to the VGA-cable, still the same.
Find
Reply
abusharif
Member
Posts: 78
#4
2009-11-12, 23:42
I think i found the error!

This only happens if you start the movie from LIBRARY mode. Turn of Library mode in the Video and start video from the share or whatever. Errors stop = no crashing.

I beleive its the autoscan feature for Movie tags (720p, SD etc) that is constantly parsing certain movies and has problems detecting them so it causes loop!

Following error message is in the debug log prior to crash (when loop or whatever u wanna call it dies)

Code:
22:22:04 T:2982128528 M:1457946624   DEBUG: DoWork - trying to extract thumb from video file smb://DLINK/Volume_2/Video/Movies HD/3 10 To Yuma/s-310toyuma.720p.mkv
22:33:47 T:2982128528 M:1389989888   DEBUG: ExtractThumb - measured 703498 ms to extract thumb from file <smb://DLINK/Volume_2/Video/Movies HD/3 10 To Yuma/s-310toyuma.720p.mkv>
22:33:47 T:2982128528 M:1390002176   DEBUG: DoWork - trying to extract thumb from video file smb://DLINK/Volume_1/nd_Movies HD/My Sisters Keeper/cbgb-msk1080.mkv

ALSO! - If u check the row above ExtractThumb - measured 703498 ms its aproximately 11 minutes = Exact timing movies crash each time!

Followed by:

Code:
22:33:48 T:2982128528 M:1389514752   DEBUG: SeekTime - seek ended up on time 2186349
22:33:48 T:2982128528 M:1389477888  NOTICE:  (VDPAU) Didnt find a Video Surface Available (Total: 10). Creating a new one. TRY #1
22:33:48 T:2982128528 M:1386508288   ERROR:  (VDPAU) Error: The size of a supplied object does not match the object it is being used with.(20) at VDPAU.cpp:1061
22:33:48 T:2982128528 M:1386508288  NOTICE: Attempting recovery
22:33:48 T:2982128528 M:1386508288  NOTICE:  (VDPAU) FiniVDPAUOutput
22:33:48 T:2834590608 M:1411321856   ERROR:  (VDPAU) Error: An invalid handle value was provided.(3) at VDPAU.cpp:1143
22:33:49 T:2834590608 M:1424162816   ERROR:  (VDPAU) Error: An invalid handle value was provided.(3) at VDPAU.cpp:1061
22:33:49 T:2834590608 M:1424162816  NOTICE:  (VDPAU) Creating the video mixer
22:33:49 T:2834590608 M:1424162816   ERROR:  (VDPAU) Error: An invalid handle value was provided.(3) at VDPAU.cpp:327
22:33:49 T:2834590608 M:1424162816   ERROR:  (VDPAU) Error: An invalid handle value was provided.(3) at VDPAU.cpp:377
22:33:49 T:2834590608 M:1424162816   ERROR:  (VDPAU) Error: An invalid handle value was provided.(3) at VDPAU.cpp:1127
22:33:49 T:2834590608 M:1424162816   ERROR:  (VDPAU) Error: An invalid handle value was provided.(3) at VDPAU.cpp:1143
22:33:49 T:2982128528 M:1424060416  NOTICE:  (VDPAU) Creating the video mixer
22:33:49 T:2834590608 M:1423011840  NOTICE:  (VDPAU) Didnt find a Video Surface Available (Total: 0). Creating a new one. TRY #1
22:33:49 T:2982128528 M:1420144640   ERROR:  (VDPAU) Error: An invalid handle value was provided.(3) at VDPAU.cpp:1127
22:33:49 T:2982128528 M:1420144640  NOTICE: Attempting recovery
22:33:49 T:2982128528 M:1420144640  NOTICE:  (VDPAU) FiniVDPAUOutput
22:33:49 T:2982128528 M:1420144640   ERROR:  (VDPAU) Error: An invalid handle value was provided.(3) at VDPAU.cpp:767
22:33:49 T:2982128528 M:1420144640   ERROR:  (VDPAU) Error: An invalid handle value was provided.(3) at VDPAU.cpp:770
22:33:49 T:2982128528 M:1420144640   ERROR:  (VDPAU) Error: An invalid handle value was provided.(3) at VDPAU.cpp:773
22:33:49 T:2982128528 M:1420144640   ERROR:  (VDPAU) Error: An invalid handle value was provided.(3) at VDPAU.cpp:778
22:33:49 T:2982128528 M:1420144640   ERROR:  (VDPAU) Error: An invalid handle value was provided.(3) at VDPAU.cpp:778
22:33:49 T:2982128528 M:1420144640   ERROR:  (VDPAU) Error: An invalid handle value was provided.(3) at VDPAU.cpp:778
22:33:49 T:2982128528 M:1420144640   ERROR:  (VDPAU) Error: An invalid handle value was provided.(3) at VDPAU.cpp:778
Find
Reply
abusharif
Member
Posts: 78
#5
2009-11-12, 23:46
I am 100% sure this is the cause, but you can try it out as well pal.
If it helps you as well, change the subject of this thread to Confirmed BUG or whatever to draw the developers attention :p
Find
Reply
jinxedworld
Junior Member
Posts: 11
#6
Thumbs Up  2009-11-12, 23:52
Dude, that's it Smile. Solved it for me! But I didn't disable library mode though, but your remark about thumbs put me on the right track!

I went to Settings -> Videos, and switched off "Extract thumbs and flags".

From that point on it works flawlessly, and no more errors!

Nice job Smile
Find
Reply
abusharif
Member
Posts: 78
#7
2009-11-12, 23:54
jinxedworld Wrote:Dude, that's it Smile. Solved it for me! But I didn't disable library mode though, but your remark about thumbs put me on the right track!

I went to Settings -> Videos, and switched off "Extract thumbs and flags".

From that point on it works flawlessly, and no more errors!

Nice job Smile


Ah ofc yeah think ill disable that as well. Got arround 200 movies, and for some reason on 3 or 4 of them it has issues extracting. (All of them worked in 9.04 , so its something wierdo with the alpha version).

Now we can both sleep well Smile
Find
Reply
jinxedworld
Junior Member
Posts: 11
#8
2009-11-12, 23:55
abusharif Wrote:I am 100% sure this is the cause, but you can try it out as well pal.
If it helps you as well, change the subject of this thread to Confirmed BUG or whatever to draw the developers attention :p

I changed the topic title, but i'm sure we've got to submit a good bug report Smile. I'll do that in the morning, now it's time for bed (for me anyways Smile )
Find
Reply
motd2k
Team-XBMC Developer
Posts: 666
#9
2009-11-13, 01:37
Its been fixed on SVN for about a week
Overplay - Ultra-high speed VPN servers in 36 countries.
Find
Reply
jinxedworld
Junior Member
Posts: 11
#10
2009-11-13, 09:38
motd2k Wrote:Its been fixed on SVN for about a week

Ok, didn't know that Smile. Is there any way to update the installation of XBMC on XBMClive?
Find
Reply
abusharif
Member
Posts: 78
#11
2009-11-13, 09:51
motd2k Wrote:Its been fixed on SVN for about a week

Hi,

Good to hear. Which SVN would that be? apt-get update/upgrade doesnt show any xbmc packages that can be updated.

Thanks
Find
Reply
abusharif
Member
Posts: 78
#12
2009-11-13, 10:31
Nevermind, this morning there was new release on svn. Running now " Built on Nov 12 2009 (SVN:24548)"

Code:
deb http://ppa.launchpad.net/xbmc-addons/ppa/ubuntu jaunty main
deb-src http://ppa.launchpad.net/xbmc-addons/ppa/ubuntu jaunty main
deb http://ppa.launchpad.net/nvidia-vdpau/ppa/ubuntu jaunty main
deb-src http://ppa.launchpad.net/nvidia-vdpau/ppa/ubuntu jaunty main
deb http://ppa.launchpad.net/team-xbmc/jaunty-ppa/ubuntu jaunty main
deb-src http://ppa.launchpad.net/team-xbmc/jaunty-ppa/ubuntu jaunty main
Find
Reply
jinxedworld
Junior Member
Posts: 11
#13
2009-11-13, 10:47
Yes I noticed that too, saw an Alpha2 and decide to give it a go, and it went all screwy in my system.... I upgraded all packages, and now i can't get into my system anymore.

I think it did something to the sudoers file (like re-adding user "xbmc") which generated a parse error, and now it's impossible for me to sudo into root. It seems that XBMC has started though.

Here's some output from after the reboot:

Code:
(live)xbmc@XBMCLive:~$ sudo su
>>> /etc/sudoers: Alias `' already defined near line 33 <<<
>>> /etc/sudoers: Alias `' already defined near line 34 <<<
sudo: parse error in /etc/sudoers near line 33
sudo: no valid sudoers sources found, quitting
(live)xbmc@XBMCLive:~$

Trying to start XBMC-live from the "xbmc" user:
Code:
(live)xbmc@XBMCLive:~$ service xbmc-live start
start: Rejected send message, 1 matched rules; type="method_call", sender=":1.21" (uid=1000 pid=1751 comm="start) interface="com.ubuntu.Upstart0_6.Job" member="Start" error name="(unset)" requested_reply=0 destination="com.ubuntu.Upstart" (uid=0 pid=1 comm="/sbin/init"))
selective output from "ps faxuwww" command:
Code:
xbmc      1512  0.0  0.0   4484  1540 ?        S    09:03   0:00 /bin/bash /usr/bin/runXBMC
xbmc      1533  0.0  0.0   4496  1572 ?        S    09:03   0:00  \_ /bin/bash /usr/bin/startx -- -br
xbmc      1551  0.0  0.0   3052   800 ?        S    09:03   0:00      \_ xinit /etc/X11/xinit/xinitrc -- /usr/bin/X -br -auth /tmp/serverauth.NyjjP0evsg
root      1552  0.0  1.4 294116 26960 tty7     Ss+  09:03   0:01          \_ /usr/bin/X :0 -br -auth /tmp/serverauth.NyjjP0evsg
xbmc      1566  0.0  0.0   1748   492 ?        S    09:03   0:00          \_ sh /home/xbmc/.xsession
xbmc      1583  0.0  0.0   5808   612 ?        Ss   09:03   0:00              \_ /usr/bin/ssh-agent sh /home/xbmc/.xsession
xbmc      1584 15.6  2.9 115096 52416 ?        SLl  09:03   6:28              \_ /usr/share/xbmc/xbmc.bin --standalone

So the primary error is not being able to SUDO. I can't look on my TV to figure out if XBMC is running for sure, since i'm not at home.

(No hard feelings, I know this is Alpha stuff, so we knew what we were getting into Smile )
Find
Reply
lcapriotti
Retired Team-Kodi Member
Posts: 854
#14
2009-11-13, 11:00
alpha2 went out without the committed fix, pls bear with me for fixing it too late Smile
Find
Reply
jinxedworld
Junior Member
Posts: 11
#15
Thumbs Up  2009-11-13, 11:08
No problem, I'll fix it manually when I get home Smile

Still kudo's to the entiry dev team!

For posterity's sake i'll sribble down my probable fix (untested, doing this by heart):

1: Boot an Ubuntu CD and enter rescue mode
2: Fill out the mandatory questions
3: Go to the target mount where rescue mode mounted your harddisk's filesystem
4: fix the file /etc/suoders yourself (remove the double line should do it )
Find
Reply

Home
Search
Help
Logout Mark Read Team Forum Stats Members Help
[LIVE] Lots of errors in xbmc.log when playing HD file2