Video Slow & decode_slice_header error
#1
Running Debian Wheezy x64 w/ XBMC Frodo RC3. Computer is quad core Q9550 with 4 gigs of ram & Nvidia 7950.
Problem: I am trying to watch a simple 720p TV show, but the video is shuddering frequently. Every 20 seconds or so, the framerate drops significantly.

Code:
21:42:30 T:140582879966976   DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /media/raid_array/tv_complete/redacted/redacted.mkv
21:42:30 T:140582879966976   DEBUG: CDVDPlayer::SetCaching - caching state 3
21:42:30 T:140582768850688    INFO: CAEStreamInfo::SyncAC3 - AC3 stream detected (6 channels, 48000Hz)
21:42:30 T:140582768850688   DEBUG: CDVDPlayerAudio: Sample rate changed, checking for passthrough
21:42:30 T:140582768850688   DEBUG: DllAvUtilBase: Using libavutil system library
21:42:30 T:140582768850688   DEBUG: FactoryCodec - Audio: passthrough - Opening
21:42:30 T:140582768850688   DEBUG: FactoryCodec - Audio: passthrough - Opened
21:42:30 T:140582768850688  NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, pass-through)
21:42:30 T:140582768850688    INFO: CSoftAE::MakeStream - AE_FMT_AC3, 48000, RAW,RAW
21:42:30 T:140582777243392    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
21:42:30 T:140582777243392   DEBUG: VAAPI - attempting to open codec 28 with profile 100 at level 31 with 2 reference frames
21:42:30 T:140582777243392   DEBUG: VAAPI - initialize version 0.32
21:42:30 T:140582777243392   ERROR: VAAPI - unable to initialize display -1 - unknown libva error
21:42:30 T:140583159478016   DEBUG: CSoftAE::Run - Sink restart flagged
21:42:30 T:140583159478016    INFO: CSoftAE::InternalOpenSink - RAW passthrough enabled
21:42:30 T:140583159478016    INFO: CSoftAE::InternalOpenSink - sink incompatible, re-starting
21:42:30 T:140582777243392  NOTICE:  fps: 23.976024, pwidth: 1280, pheight: 720, dwidth: 1280, dheight: 720
21:42:30 T:140582777243392   DEBUG: OutputPicture - change configuration. 1280x720. framerate: 23.98. format: YV12
21:42:30 T:140583563671488   DEBUG: CGUIInfoManager::SetCurrentMovie(/media/raid_array/tv_complete/redacted/redacted.mkv)
21:42:30 T:140583563671488   DEBUG: GetMovieId (/media/raid_array/tv_complete/redacted/redacted.mkv), query = select idMovie from movie where idFile=1326
21:42:30 T:140583563671488   DEBUG: GetEpisodeId (/media/raid_array/tv_complete/redacted/redacted.mkv), query = select idEpisode from episode where idFile=1326
21:42:30 T:140583563671488   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
21:42:30 T:140583563671488   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
21:42:30 T:140582879966976   DEBUG: CDVDPlayer::SetCaching - caching state 0
21:42:30 T:140582777243392 WARNING: CRenderManager::Configure - timeout waiting for previous frame
21:42:30 T:140582777243392  NOTICE: Display resolution DESKTOP : 1280x720 @ 60.00 - Full Screen (16)
21:42:30 T:140583563671488   DEBUG: Activating window ID: 12005
21:42:30 T:140583563671488   DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
21:42:30 T:140583563671488   DEBUG: ------ Window Init (VideoFullScreen.xml) ------
21:42:30 T:140583159478016    INFO: CAESinkALSA::Initialize - Attempting to open device "iec958:CARD=Intel,DEV=0"
21:42:30 T:140583159478016    INFO: CAESinkALSA::Initialize - Opened device "iec958:CARD=Intel,DEV=0,AES0=0x06,AES1=0x82,AES2=0x00,AES3=0x02"
21:42:30 T:140583159478016   DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 512, periods 16, bufferSize 8192
21:42:30 T:140583159478016   DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 512, periods 16, bufferSize 8192
21:42:30 T:140583159478016   DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 171 ms
21:42:30 T:140583159478016   DEBUG: CSoftAE::InternalOpenSink - ALSA Initialized:
21:42:30 T:140583159478016   DEBUG:   Output Device : HDA Intel
21:42:30 T:140583159478016   DEBUG:   Sample Rate   : 48000
21:42:30 T:140583159478016   DEBUG:   Sample Format : AE_FMT_S16NE
21:42:30 T:140583159478016   DEBUG:   Channel Count : 2
21:42:30 T:140583159478016   DEBUG:   Channel Layout: FL,FR
21:42:30 T:140583159478016   DEBUG:   Frames        : 512
21:42:30 T:140583159478016   DEBUG:   Frame Samples : 1024
21:42:30 T:140583159478016   DEBUG:   Frame Size    : 4
21:42:30 T:140583159478016   DEBUG: CSoftAE::InternalOpenSink - Internal Buffer Size: 2048
21:42:30 T:140582768850688   DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
21:42:30 T:140583159478016   DEBUG: CSoftAE::Run - Sink restart flagged
21:42:30 T:140583159478016    INFO: CSoftAE::InternalOpenSink - RAW passthrough enabled
21:42:30 T:140583159478016    INFO: CSoftAE::InternalOpenSink - keeping old sink with : AE_FMT_AC3, FL,FR, 48000hz
21:42:30 T:140583159478016   DEBUG: CSoftAE::InternalOpenSink - Internal Buffer Size: 2048
21:42:30 T:140582768850688   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(32000.000000, 1)
21:42:30 T:140582768850688   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:-6589.980999, should be:-6589.195000, error:0.786000
21:42:30 T:140582879966976   DEBUG: CDVDPlayer::HandleMessages - player started 1
21:42:30 T:140583563671488  NOTICE: Using GL_TEXTURE_2D
21:42:30 T:140583563671488   DEBUG: GL: Requested render method: 0
21:42:30 T:140583563671488   DEBUG: GL: BaseYUV2RGBGLSLShader: defines:
                                            #define XBMC_texture_rectangle 0
                                            #define XBMC_texture_rectangle_hack 0
                                            #define XBMC_STRETCH 0
                                            #define XBMC_YV12
21:42:30 T:140583563671488  NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
21:42:30 T:140583563671488   DEBUG: GL: Vertex Shader compilation log:
21:42:30 T:140583563671488   DEBUG: GL: Vertex Shader compiled successfully
21:42:30 T:140583563671488   DEBUG: GL: Pixel Shader compilation log:
21:42:30 T:140583563671488   DEBUG: GL: Fragment Shader compiled successfully
21:42:30 T:140583563671488  NOTICE: GL: NPOT texture support detected
21:42:30 T:140583563671488  NOTICE: GL: Using GL_ARB_pixel_buffer_object
21:42:30 T:140582777243392   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(83000.000000, 0)
21:42:30 T:140582879966976   DEBUG: CDVDPlayer::HandleMessages - player started 2
21:42:30 T:140582777243392   DEBUG: ffmpeg[FA7FC700]: [h264] Increasing reorder buffer to 1
21:42:30 T:140582777243392   ERROR: ffmpeg[FA7FC700]: [h264] Changing field mode (3 -> 3) between slices is not allowed
21:42:30 T:140582777243392   ERROR: ffmpeg[FA7FC700]: [h264] decode_slice_header error
21:42:30 T:140582777243392   ERROR: ffmpeg[FA7FC700]: [h264] Changing field mode (3 -> 3) between slices is not allowed
21:42:30 T:140582777243392   ERROR: ffmpeg[FA7FC700]: [h264] decode_slice_header error
21:42:30 T:140582777243392   ERROR: ffmpeg[FA7FC700]: [h264] Changing field mode (3 -> 3) between slices is not allowed
21:42:30 T:140582777243392   ERROR: ffmpeg[FA7FC700]: [h264] decode_slice_header error
21:42:30 T:140582777243392   ERROR: ffmpeg[FA7FC700]: [h264] Changing field mode (3 -> 3) between slices is not allowed
21:42:30 T:140582777243392   ERROR: ffmpeg[FA7FC700]: [h264] decode_slice_header error
21:42:30 T:140582777243392   ERROR: ffmpeg[FA7FC700]: [h264] Changing field mode (3 -> 3) between slices is not allowed
21:42:30 T:140582777243392   ERROR: ffmpeg[FA7FC700]: [h264] decode_slice_header error
21:42:30 T:140582777243392   ERROR: ffmpeg[FA7FC700]: [h264] Changing field mode (3 -> 3) between slices is not allowed
21:42:30 T:140582777243392   ERROR: ffmpeg[FA7FC700]: [h264] decode_slice_header error
21:42:30 T:140582777243392   ERROR: ffmpeg[FA7FC700]: [h264] Changing field mode (3 -> 3) between slices is not allowed
21:42:30 T:140582777243392   ERROR: ffmpeg[FA7FC700]: [h264] decode_slice_header error
21:42:30 T:140582777243392    INFO: ffmpeg[FA7FC700]: [h264] concealing 3150 DC, 3150 AC, 3150 MV errors in B frame
21:42:30 T:140582777243392   ERROR: ffmpeg[FA7FC700]: [h264] Changing field mode (3 -> 3) between slices is not allowed

Please help me diagnose this issue.
thanks!
Reply
#2
Thanks for the complete log file!
First decide what functions / features you expect from a system. Then decide for the hardware. Don't waste your money on crap.
Reply
#3
The log file is 5000 lines long. I left out the beginning because it looked unnecessarily long and I was concerned about private data being embedded somewhere in there. Let me know if anyone needs that.
Reply
#4
(2013-01-29, 14:02)oddworld Wrote: The log file is 5000 lines long. I left out the beginning because it looked unnecessarily long and I was concerned about private data being embedded somewhere in there. Let me know if anyone needs that.

the full Debug Log
Read/follow the forum rules.
For troubleshooting and bug reporting, read this first
Interested in seeing some YouTube videos about Kodi? Go here and subscribe
Reply
#5
couple of things:
1.) run play a 23,98hz movie on 60Hz display -> telecine judder
2.) 21:42:30 T:140582777243392 ERROR: VAAPI - unable to initialize display -1 - unknown libva error
-> install libva1
3.)21:42:30 T:140582768850688 DEBUG: DllAvUtilBase: Using libavutil system library
-> seems libav is used instead of ffmpeg
Reply
#6
Posted a link:

http://pastebin.com/JXtdbg1F
or
http://xbmclogs.com/show.php?id=31072
Reply
#7
(2013-01-29, 14:53)wsnipex Wrote: couple of things:
1.) run play a 23,98hz movie on 60Hz display -> telecine judder
2.) 21:42:30 T:140582777243392 ERROR: VAAPI - unable to initialize display -1 - unknown libva error
-> install libva1
3.)21:42:30 T:140582768850688 DEBUG: DllAvUtilBase: Using libavutil system library
-> seems libav is used instead of ffmpeg


1) This is more than the general telecine judder. It is sporadic, maybe every 20 seconds or so. It is not a consistent judder.

2) libva1 was already installed.

Code:
root@xmbc-streamer:/home/NAME_REDACTED# aptitude install libva1
No packages will be installed, upgraded, or removed.
0 packages upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
Need to get 0 B of archives. After unpacking 0 B will be used.

3) How can I ensure that ffmpeg is used instead of libav?

Thanks again for your help

Also I do not think there is an issue with the buffer. Videos are stored locally on the XBMC device, using a 4 disk RAID 5 array, having 450 MB/s read and 300 MB/s write.

Also, VDPAU not possible. Card is Nvidia 7950 (no hardware decode)
Reply

Logout Mark Read Team Forum Stats Members Help
Video Slow & decode_slice_header error0