Kodi Community Forum

Pages: 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 32 33 34 35 36 37 38 39 40 41 42 43 44 45 46 47 48 49 50 51 52 53 54 55 56 57 58 59 60 61 62 63 64 65 66 67 68 69 70 71 72 73 74 75 76 77 78 79 80 81 82 83 84 85 86 87 88 89 90 91 92 93 94 95 96 97 98 99 100 101 102 103 104 105 106 107 108 109 110 111 112 113 114 115 116 117 118 119 120 121 122 123 124 125 126 127 128

Nice. thanks for this detailled report. Flushing renderer / decoder on fullscreen change should help here.

Thanks for testing and getting the backtrace. Upstream PR is here: https://github.com/xbmc/xbmc/pull/2460 - we hope, that it can go into mainline within the next time.

Glad I could help!
With next time you mean 12.4 ?

EDIT: can I try to apply the 3 commits directly on the latest frodo ? Or is it to be applied on main ?

That said, can I use https://github.com/BtbN/xbmc/commits/vaa...nterlacing to try ?

Indeed, no crash on latest main with the vaap-vpp patch..

EDIT:

I spoke too quickly...
No crash when pressing \, but random crash in window mode when launching a video. Will edit with debug.

Code:
#0  0x00007fffe44dd2ae in ?? () from /usr/lib/x86_64-linux-gnu/dri/i965_dri.so

#1  0x00007fffe44d3220 in ?? () from /usr/lib/x86_64-linux-gnu/dri/i965_dri.so

#2  0x00007fffe44bd5a0 in ?? () from /usr/lib/x86_64-linux-gnu/dri/i965_dri.so

#3  0x00007fffe44ab047 in ?? () from /usr/lib/x86_64-linux-gnu/dri/i965_dri.so

#4  0x00007fffe45c9e83 in ?? () from /usr/lib/x86_64-linux-gnu/dri/i965_dri.so

#5  0x00007fffe4655008 in ?? () from /usr/lib/x86_64-linux-gnu/dri/i965_dri.so

#6  0x00000000010a4264 in ClearBuffers (color=<optimized out>, this=<optimized out>) at RenderSystemGL.cpp:275

#7  CRenderSystemGL::ClearBuffers (this=<optimized out>, color=<optimized out>) at RenderSystemGL.cpp:262

#8  0x000000000080028e in CGUIWindowManager::RenderPass (this=this@entry=0x1696840) at GUIWindowManager.cpp:533

#9  0x0000000000800583 in CGUIWindowManager::Render (this=0x1696840) at GUIWindowManager.cpp:578

#10 0x0000000000ba8b86 in CApplication::RenderNoPresent (this=<optimized out>) at Application.cpp:2014

#11 0x0000000000ba9071 in CApplication::Render (this=0x1691930) at Application.cpp:2104

#12 0x0000000000c33801 in CXBApplicationEx::Run (this=0x1691930) at XBApplicationEx.cpp:175

#13 0x0000000000709f17 in XBMC_Run (renderGUI=true) at xbmc.cpp:69

#14 0x00000000006c8b8b in main (argc=1, argv=0x7fffffffeae8) at main.cpp:76

Another bug while running SD after an HD. It is on the btbn vaapi mainstream branch :\

Code:
libva info: VA-API version 0.34.0

libva info: va_getDriverName() returns 0

libva info: Trying to open /usr/lib/x86_64-linux-gnu/dri/i965_drv_video.so

libva info: Found init function __vaDriverInit_0_34

libva info: va_openDriver() returns 0

[New Thread 0x7fff6affd700 (LWP 2470)]

xbmc.bin: i965_drv_video.c:2013: i965_EndPicture: Assertion `obj_context->codec_state.decode.pic_param' failed.

Program received signal SIGABRT, Aborted.

[Switching to Thread 0x7fff69ffb700 (LWP 2467)]

0x00007fffef328295 in raise () from /lib/x86_64-linux-gnu/libc.so.6

(gdb) bt

#0  0x00007fffef328295 in raise () from /lib/x86_64-linux-gnu/libc.so.6

#1  0x00007fffef32b438 in abort () from /lib/x86_64-linux-gnu/libc.so.6

#2  0x00007fffef321322 in ?? () from /lib/x86_64-linux-gnu/libc.so.6

#3  0x00007fffef3213d2 in __assert_fail () from /lib/x86_64-linux-gnu/libc.so.6

#4  0x00007fff694f8baf in ?? () from /usr/lib/x86_64-linux-gnu/dri/i965_drv_video.so

#5  0x00007ffff7bcb763 in vaEndPicture () from /usr/lib/x86_64-linux-gnu/libva.so.1

#6  0x00007fff73694174 in ff_vaapi_render_picture (vactx=vactx@entry=0x7fff60000b00, surface=67108864) at libavcodec/vaapi.c:77

#7  0x00007fff73694657 in ff_vaapi_mpeg_end_frame (avctx=0x7fff6c0f4a60) at libavcodec/vaapi.c:209

#8  0x00007fff735bbccf in slice_end (pict=0x7fff6c0f4ec0, avctx=0x7fff6c0f4a60) at libavcodec/mpeg12.c:1966

#9  decode_chunks (avctx=avctx@entry=0x7fff6c0f4a60, picture=picture@entry=0x7fff6c0f4ec0, got_output=got_output@entry=0x7fff69ffaa4c, buf=0x7fff78006b10 "", buf_size=13937) at libavcodec/mpeg12.c:2295

#10 0x00007fff735bd6c4 in mpeg_decode_frame (avctx=0x7fff6c0f4a60, data=0x7fff6c0f4ec0, got_output=0x7fff69ffaa4c, avpkt=<optimized out>) at libavcodec/mpeg12.c:2600

#11 0x00007fff7368e799 in avcodec_decode_video2 (avctx=0x7fff6c0f4a60, picture=0x7fff6c0f4ec0, got_picture_ptr=0x7fff69ffaa4c, avpkt=0x7fff69ffaa70) at libavcodec/utils.c:1690

#12 0x000000000089412f in avcodec_decode_video2 (p4=0x7fff69ffaa60, p3=0x7fff69ffaa3c, p2=<optimized out>, p1=<optimized out>, this=0x7fff6c06b980) at /home/src/xbmc/btbn/xbmc/lib/DllAvCodec.h:205

#13 CDVDVideoCodecFFmpeg::Decode (this=0x7fff6c06b920, pData=0x7fff78006b10 "", iSize=13937, dts=78674788956, pts=<optimized out>) at DVDVideoCodecFFmpeg.cpp:453

#14 0x0000000000eaf53e in CDVDPlayerVideo::Process (this=0x53d44b8) at DVDPlayerVideo.cpp:552

#15 0x00000000010dadc0 in CThread::Action (this=0x53d44b8) at Thread.cpp:218

#16 0x00000000010db072 in CThread::staticThread (data=0x53d44b8) at Thread.cpp:128

#17 0x00007ffff4b46e0e in start_thread () from /lib/x86_64-linux-gnu/libpthread.so.0

#18 0x00007fffef3db95d in clone () from /lib/x86_64-linux-gnu/libc.so.6

EDIT: it happens also when decoding for the first time

Latest mainline (xbmc master branch) is broken for mpeg-2 via VAAPI - which is an MPEG-2/ffmpeg problem.
That is also the reason i made the frodo-vpp branch to be able to test the implementation without running into mpeg-2 problems - that are ffmpeg introduced.

Bugreport here: https://ffmpeg.org/trac/ffmpeg/ticket/2461

PS: Compare your stacktrace and the one in the bugreport :-) ffmpeg 1.3 is a sad thing for vaapi, as this mpeg-2 is standard LiveTV stream for SD channels.

Hi,

I tried vaapi-vpp-deinterlacing-frodo branch from btbn github and the bugs disappeared ...
\ is now working and I did not expercienced a playback crash.

Strange isnt it ?

EDIT: i have a crash but only if I press \ to go from FS to Window mode, while doing a playback...

Region for name 16 already exists but is not compatible

It is not strange. Btbn's frodo branch with vpp is highly different from the one in the ppa, both share of course btbn's vpp work.

Hello,

unfortunately i'm having a lot of drops in LiveTV with vpp-deinterlacing enabled on my system.
I'm using a Celeron 847 with HD2000 on quantal xbmcbuntu (32Bit) with 3.7.0-7 kernel.
I added this ppa additionally to xorg-edgers ppa, disabled XBMC-main ppa and ran apt-get update/upgrade to update to the vpp drivers and xbmc.
All settings from first post applied.

My codec info looks like that :

my xbmc.log with debug enabled:

Code:
10:45 T:3038050048   DEBUG: OnKey: down (f081) pressed, action is ChannelDown

10:45 T:2511338304   DEBUG: CSoftAEStream::Flush

10:45 T:2571168576   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE

10:45 T:2511338304   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE

10:45 T:2781215552   DEBUG: CDVDPlayer::SetCaching - caching state 3

10:45 T:2781215552   DEBUG: PVRManager - PerformChannelSwitch - switching to channel 'glitz* HD'

10:45 T:2781215552   DEBUG: PVR - SaveCurrentChannelSettings - no custom channel settings for channel 'Syfy HD'

10:45 T:2781215552    INFO: AddOnLog: Tvheadend HTSP Client: SwitchChannel - changing to channel 'glitz* HD'

10:45 T:2781215552    INFO: AddOnLog: Tvheadend HTSP Client: SendUnsubscribe - unsubscribe from subscription 2

10:45 T:2781215552    INFO: AddOnLog: Tvheadend HTSP Client: SendSubscribe - subscribe to channel '114', subscription 3

10:45 T:2970651456   DEBUG: CSoftAE::Run - Sink restart flagged

10:45 T:2970651456    INFO: CSoftAE::InternalOpenSink - RAW passthrough enabled

10:45 T:2970651456    INFO: CSoftAE::InternalOpenSink - keeping old sink with : AE_FMT_AC3, FL,FR, 48000hz

10:45 T:2970651456   DEBUG: CSoftAE::InternalOpenSink - Internal Buffer Size: 9600

10:45 T:2781215552   DEBUG: AddOnLog: Tvheadend HTSP Client: SendSubscribe - new subscription for channel 115 (3)

10:45 T:2781215552  NOTICE: PVRManager - PerformChannelSwitch - switched to channel 'glitz* HD'

10:45 T:2781215552  NOTICE: Creating Demuxer

10:45 T:2781215552  NOTICE: Closing video stream

10:45 T:2781215552   DEBUG: CDVDPlayer::SetCaching - caching state 0

10:45 T:2781215552  NOTICE: waiting for video thread to exit

10:45 T:2571168576   ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true

10:45 T:2571168576  NOTICE: thread end: video_thread

10:45 T:2571168576   DEBUG: Thread CDVDPlayerVideo 2571168576 terminating

10:45 T:2700073792   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc

10:45 T:2700073792   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop

10:45 T:2700073792   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc

10:45 T:2700073792   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay

10:45 T:2700073792   DEBUG: Building didl for object 'pvr://channels/tv/Alle TV-Kanäle/63.pvr'

10:45 T:2781215552  NOTICE: deleting video codec

10:45 T:2539088704   DEBUG: VAAPI - VPP thread on exit

10:45 T:2539088704   DEBUG: Thread VAAPI VPP Thread 2539088704 terminating

10:45 T:2781215552   DEBUG: VAAPI_VPP - Deinitializing

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x4000016

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x4000015

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x4000014

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x4000012

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x4000011

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x4000002

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x400000d

10:45 T:2781215552   DEBUG: VAAPI_VPP - Deinitialized bob

10:45 T:2781215552   DEBUG: VAAPI_VPP - Deinitialized vpp

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x4000003

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x4000000

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x400000e

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x400000c

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x4000006

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x4000004

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x4000009

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x4000007

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x400000a

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x400000b

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x4000001

10:45 T:2781215552   DEBUG: VAAPI - destroying surface 0x4000005

10:45 T:2781215552  NOTICE: Closing audio stream

10:45 T:2781215552  NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty

10:45 T:2511338304   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE

10:45 T:2511338304   DEBUG: CDVDPlayerAudio::Process - Abort received, exiting thread

10:45 T:2511338304  NOTICE: thread end: CDVDPlayerAudio::OnExit()

10:45 T:2511338304   DEBUG: Thread CDVDPlayerAudio 2511338304 terminating

10:45 T:2970651456   DEBUG: CSoftAE::Run - Sink restart flagged

10:45 T:2970651456    INFO: CSoftAE::InternalOpenSink - RAW passthrough enabled

10:45 T:2970651456    INFO: CSoftAE::InternalOpenSink - keeping old sink with : AE_FMT_AC3, FL,FR, 48000hz

10:45 T:2970651456   DEBUG: CSoftAE::InternalOpenSink - Internal Buffer Size: 9600

10:45 T:2781215552  NOTICE: Waiting for audio thread to exit

10:45 T:2781215552  NOTICE: Closing audio device

10:45 T:2781215552   DEBUG: CSoftAEStream::~CSoftAEStream - Destructed

10:45 T:2781215552  NOTICE: Deleting audio codec

10:45 T:2781215552   DEBUG: CDVDPlayer::SetCaching - caching state 2

10:45 T:2781215552 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED

10:45 T:2781215552 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED

10:45 T:2781215552   DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/Alle TV-Kanäle/62.pvr)

10:45 T:2970651456   DEBUG: CSoftAE::Run - Sink restart flagged

10:45 T:2970651456    INFO: CSoftAE::InternalOpenSink - sink incompatible, re-starting

10:45 T:2970651456    INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0"

10:45 T:2970651456    INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"

10:45 T:2970651456    INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats

10:45 T:2970651456    INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE

10:45 T:2970651456   DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2048, bufferSize 8192

10:45 T:2970651456   DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192

10:45 T:2970651456   DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 186 ms

10:45 T:2970651456   DEBUG: CSoftAE::InternalOpenSink - ALSA Initialized:

10:45 T:2970651456   DEBUG:   Output Device : HDA Intel PCH

10:45 T:2970651456   DEBUG:   Sample Rate   : 44100

10:45 T:2970651456   DEBUG:   Sample Format : AE_FMT_S32NE

10:45 T:2970651456   DEBUG:   Channel Count : 2

10:45 T:2970651456   DEBUG:   Channel Layout: FL,FR

10:45 T:2970651456   DEBUG:   Frames        : 2048

10:45 T:2970651456   DEBUG:   Frame Samples : 4096

10:45 T:2970651456   DEBUG:   Frame Size    : 8

10:45 T:2970651456   DEBUG: CSoftAE::InternalOpenSink - Using speaker layout: 5.1

10:45 T:2970651456   DEBUG: CSoftAE::InternalOpenSink - Internal Buffer Size: 16384

10:46 T:2716859200   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 1, type: H264

10:46 T:2716859200   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 2, type: AC3

10:46 T:2716859200   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 3, type: AC3

10:46 T:2716859200   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 4, type: CA

10:46 T:2716859200   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 5, type: CA

10:46 T:2716859200   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 6, type: CA

10:46 T:2716859200    INFO: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - subscription started on adapter STV0367 DVB-C DVB-T, mux Kabel Deutschland: 434,000 kHz, network Kabel Deutschland, provider KD Home, service glitz* HD

10:46 T:2781215552   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 0:1 with codec_id 28

10:46 T:2781215552   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 1:2 with codec_id 86019

10:46 T:2781215552   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 2:3 with codec_id 86019

10:46 T:2781215552  NOTICE: Opening video stream: 0 source: 256

10:46 T:2781215552  NOTICE: Creating video codec with codec id: 28

10:46 T:2781215552   DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no OpenMax:no VDPAU:yes VAAPI:yes XVBA:yes

10:46 T:2781215552   DEBUG: FactoryCodec - Video:  - Opening

10:46 T:2781215552  NOTICE: CDVDVideoCodecFFmpeg::Open() Creating VDPAU(0x0, 28)

10:46 T:2781215552 WARNING: (VDPAU) no width/height available, can't init

10:46 T:2781215552  NOTICE: CDVDVideoCodecFFmpeg::Open() Failed to get VDPAU device

10:46 T:2781215552  NOTICE:  (VDPAU) Close

10:46 T:2781215552  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10

10:46 T:2781215552   DEBUG: FactoryCodec - Video: ff-h264 - Opened

10:46 T:2781215552  NOTICE: Creating video thread

10:46 T:2530503488  NOTICE: Thread CDVDPlayerVideo start, auto delete: false

10:46 T:2781215552  NOTICE: Opening audio stream: 1 source: 256

10:46 T:2781215552  NOTICE: Finding audio codec for: 86019

10:46 T:2781215552   DEBUG: FactoryCodec - Audio: passthrough - Opening

10:46 T:2781215552   DEBUG: FactoryCodec - Audio: passthrough - Opened

10:46 T:2781215552  NOTICE: Creating audio thread

10:46 T:2530503488  NOTICE: running thread: video_thread

10:46 T:2530503488   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE

10:46 T:2571168576  NOTICE: Thread CDVDPlayerAudio start, auto delete: false

10:46 T:2571168576  NOTICE: running thread: CDVDPlayerAudio::Process()

10:46 T:3038050048   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 0

10:46 T:2716859200   DEBUG: Previous line repeats 30 times.

10:46 T:2716859200    INFO: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStop - subscription ended on adapter STV0367 DVB-C DVB-T

10:46 T:2716859200   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 1, type: H264

10:46 T:2716859200   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 2, type: AC3

10:46 T:2716859200   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 3, type: AC3

10:46 T:2716859200   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 4, type: CA

10:46 T:2716859200   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 5, type: CA

10:46 T:2716859200   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 6, type: CA

10:46 T:2716859200    INFO: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - subscription started on adapter STV0367 DVB-C DVB-T, mux Kabel Deutschland: 434,000 kHz, network Kabel Deutschland, provider KD Home, service glitz* HD

10:46 T:2781215552   DEBUG: ParsePacket - {0} profile changed from -99 to 100

10:46 T:2781215552   DEBUG: ParsePacket - {0} level changed from 0 to 40

10:46 T:2781215552  NOTICE: Opening video stream: 0 source: 256

10:46 T:2781215552  NOTICE: Creating video codec with codec id: 28

10:46 T:2781215552   DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no OpenMax:no VDPAU:yes VAAPI:yes XVBA:yes

10:46 T:2781215552   DEBUG: FactoryCodec - Video:  - Opening

10:46 T:2781215552  NOTICE: CDVDVideoCodecFFmpeg::Open() Creating VDPAU(0x0, 28)

10:46 T:2781215552 WARNING: (VDPAU) no width/height available, can't init

10:46 T:2781215552  NOTICE: CDVDVideoCodecFFmpeg::Open() Failed to get VDPAU device

10:46 T:2781215552  NOTICE:  (VDPAU) Close

10:46 T:2781215552  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10

10:46 T:2781215552   DEBUG: FactoryCodec - Video: ff-h264 - Opened

10:46 T:2530503488   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(0.000000, 1)

10:46 T:2530503488   DEBUG: VAAPI - attempting to open codec 28 with profile 100 at level 40 with 3 reference frames

10:46 T:2530503488   DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0

10:46 T:2530503488   DEBUG: VAAPI - profile 0

10:46 T:2530503488   DEBUG: VAAPI - profile 1

10:46 T:2530503488   DEBUG: VAAPI - profile 5

10:46 T:2530503488   DEBUG: VAAPI - profile 6

10:46 T:2530503488   DEBUG: VAAPI - profile 7

10:46 T:2530503488   DEBUG: VAAPI - profile 8

10:46 T:2530503488   DEBUG: VAAPI - profile 9

10:46 T:2530503488   DEBUG: VAAPI - profile 10

10:46 T:2530503488   DEBUG: VAAPI - profile -1

10:46 T:2530503488   DEBUG: VAAPI - making sure 12 surfaces are allocated for given 3 references

10:46 T:2530503488   DEBUG: VAAPI_VPP - Successfully initialized VPP

10:46 T:2530503488   DEBUG: VAAPI_VPP - Successfully initialized bob

10:46 T:2539088704  NOTICE: Thread VAAPI VPP Thread start, auto delete: false

10:46 T:2539088704   DEBUG: VAAPI - VPP thread on startup

10:46 T:2530503488   ERROR: ffmpeg[96D46B40]: [h264] mmco: unref short failure

10:46 T:2781215552   ERROR: Previous line repeats 1 times.

10:46 T:2781215552   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 0:1 with codec_id 28

10:46 T:2781215552   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 1:2 with codec_id 86019

10:46 T:2781215552   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 2:3 with codec_id 86019

10:46 T:2781215552  NOTICE: Opening video stream: 0 source: 256

10:46 T:2781215552  NOTICE: Creating video codec with codec id: 28

10:46 T:2781215552   DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no OpenMax:no VDPAU:yes VAAPI:yes XVBA:yes

10:46 T:2781215552   DEBUG: FactoryCodec - Video:  - Opening

10:46 T:2781215552  NOTICE: CDVDVideoCodecFFmpeg::Open() Creating VDPAU(1920x1088, 28)

10:46 T:3038050048   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 0

10:46 T:2781215552  NOTICE: vdp_device = 0xffffffff vdp_st = 0x00000001

10:46 T:2781215552   ERROR: (VDPAU) unable to init VDPAU - vdp_st = 0x1.  Falling back.

10:46 T:2781215552  NOTICE: CDVDVideoCodecFFmpeg::Open() Failed to get VDPAU device

10:46 T:2781215552  NOTICE:  (VDPAU) Close

10:46 T:2781215552  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10

10:46 T:2781215552   DEBUG: FactoryCodec - Video: ff-h264 - Opened

10:46 T:2781215552  NOTICE: Opening audio stream: 1 source: 256

10:46 T:2539088704   DEBUG: VAAPI - VPP thread on exit

10:46 T:2539088704   DEBUG: Thread VAAPI VPP Thread 2539088704 terminating

10:46 T:2530503488   DEBUG: VAAPI_VPP - Deinitializing

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x4000010

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x400000f

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x4000016

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x4000015

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x4000014

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x4000012

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x4000011

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x4000002

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x400000d

10:46 T:2530503488   DEBUG: VAAPI_VPP - Deinitialized bob

10:46 T:2530503488   DEBUG: VAAPI_VPP - Deinitialized vpp

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x4000001

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x400000b

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x400000a

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x4000007

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x4000009

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x4000004

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x4000006

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x400000c

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x400000e

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x4000000

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x4000003

10:46 T:2530503488   DEBUG: VAAPI - destroying surface 0x4000005

10:46 T:3038050048   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 0

10:47 T:2781215552   DEBUG: Previous line repeats 54 times.

10:47 T:2781215552   DEBUG: ParsePacket - {0} profile changed from -99 to 100

10:47 T:2781215552   DEBUG: ParsePacket - {0} level changed from 0 to 40

10:47 T:2781215552  NOTICE: Opening video stream: 0 source: 256

10:47 T:2781215552  NOTICE: Creating video codec with codec id: 28

10:47 T:2781215552   DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no OpenMax:no VDPAU:yes VAAPI:yes XVBA:yes

10:47 T:2781215552   DEBUG: FactoryCodec - Video:  - Opening

10:47 T:2781215552  NOTICE: CDVDVideoCodecFFmpeg::Open() Creating VDPAU(1920x1088, 28)

10:47 T:2781215552  NOTICE: vdp_device = 0xffffffff vdp_st = 0x00000001

10:47 T:2781215552   ERROR: (VDPAU) unable to init VDPAU - vdp_st = 0x1.  Falling back.

10:47 T:2781215552  NOTICE: CDVDVideoCodecFFmpeg::Open() Failed to get VDPAU device

10:47 T:2781215552  NOTICE:  (VDPAU) Close

10:47 T:2781215552  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10

10:47 T:2781215552   DEBUG: FactoryCodec - Video: ff-h264 - Opened

10:47 T:2530503488   DEBUG: VAAPI - attempting to open codec 28 with profile 100 at level 40 with 3 reference frames

10:47 T:2530503488   DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0

10:47 T:2530503488   DEBUG: VAAPI - profile 0

10:47 T:2530503488   DEBUG: VAAPI - profile 1

10:47 T:2530503488   DEBUG: VAAPI - profile 5

10:47 T:2530503488   DEBUG: VAAPI - profile 6

10:47 T:2530503488   DEBUG: VAAPI - profile 7

10:47 T:2530503488   DEBUG: VAAPI - profile 8

10:47 T:2530503488   DEBUG: VAAPI - profile 9

10:47 T:2530503488   DEBUG: VAAPI - profile 10

10:47 T:2530503488   DEBUG: VAAPI - profile -1

10:47 T:2530503488   DEBUG: VAAPI - making sure 12 surfaces are allocated for given 3 references

10:47 T:2530503488   DEBUG: VAAPI_VPP - Successfully initialized VPP

10:47 T:2530503488   DEBUG: VAAPI_VPP - Successfully initialized bob

10:47 T:2309602112  NOTICE: Thread VAAPI VPP Thread start, auto delete: false

10:47 T:2309602112   DEBUG: VAAPI - VPP thread on startup

10:47 T:2530503488   ERROR: ffmpeg[96D46B40]: [h264] mmco: unref short failure

10:47 T:3038050048   ERROR: Previous line repeats 1 times.

10:47 T:3038050048   DEBUG: PrepareNextRender no buffer, out: 0, current: 0, display: 0

10:48 T:2530503488   DEBUG: Previous line repeats 1 times.

10:48 T:2530503488   DEBUG: VAAPI - destroying surface 0x4000008

10:48 T:2530503488   ERROR: ffmpeg[96D46B40]: [h264] mmco: unref short failure

10:48 T:2781215552   ERROR: Previous line repeats 1 times.

10:48 T:2781215552   DEBUG: CDVDPlayer::HandleMessages - player started 2

10:48 T:2781215552   DEBUG: ParsePacket - {2} level changed from 0 to -99

10:48 T:2781215552   DEBUG: ParsePacket - {2} iChannels changed from 0 to 2

10:48 T:2781215552   DEBUG: ParsePacket - {2} iSampleRate changed from 0 to 48000

10:48 T:2781215552   DEBUG: ParsePacket - {1} level changed from 0 to -99

10:48 T:2781215552   DEBUG: ParsePacket - {1} iChannels changed from 0 to 6

10:48 T:2781215552   DEBUG: ParsePacket - {1} iSampleRate changed from 0 to 48000

10:48 T:2781215552  NOTICE: Opening audio stream: 1 source: 256

10:48 T:2781215552  NOTICE: Finding audio codec for: 86019

10:48 T:2781215552   DEBUG: FactoryCodec - Audio: passthrough - Opening

10:48 T:2781215552   DEBUG: FactoryCodec - Audio: passthrough - Opened

10:48 T:2571168576   DEBUG: CDVDPlayerAudio: Sample rate changed, checking for passthrough

10:48 T:2571168576   DEBUG: FactoryCodec - Audio: passthrough - Opening

10:48 T:2571168576   DEBUG: FactoryCodec - Audio: passthrough - Opened

10:48 T:2571168576   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(18611.000000, 0)

10:48 T:2571168576    INFO: CAEStreamInfo::SyncAC3 - AC3 stream detected (6 channels, 48000Hz)

10:48 T:2571168576   DEBUG: CDVDPlayerAudio: Sample rate changed, checking for passthrough

10:48 T:2571168576   DEBUG: FactoryCodec - Audio: passthrough - Opening

10:48 T:2571168576   DEBUG: FactoryCodec - Audio: passthrough - Opened

10:48 T:2571168576  NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, pass-through)

10:48 T:2571168576    INFO: CSoftAE::MakeStream - AE_FMT_AC3, 48000, RAW,RAW

10:48 T:2970651456   DEBUG: CSoftAE::Run - Sink restart flagged

10:48 T:2970651456    INFO: CSoftAE::InternalOpenSink - RAW passthrough enabled

10:48 T:2970651456    INFO: CSoftAE::InternalOpenSink - sink incompatible, re-starting

10:48 T:2970651456    INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0"

10:48 T:2970651456    INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x06,AES1=0x82,AES2=0x00,AES3=0x02"

10:48 T:2970651456   DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600

10:48 T:2970651456   DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600

10:48 T:2970651456   DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms

10:48 T:2970651456   DEBUG: CSoftAE::InternalOpenSink - ALSA Initialized:

10:48 T:2970651456   DEBUG:   Output Device : HDA Intel PCH

10:48 T:2970651456   DEBUG:   Sample Rate   : 48000

10:48 T:2970651456   DEBUG:   Sample Format : AE_FMT_S16NE

10:48 T:2970651456   DEBUG:   Channel Count : 2

10:48 T:2970651456   DEBUG:   Channel Layout: FL,FR

10:48 T:2970651456   DEBUG:   Frames        : 2400

10:48 T:2970651456   DEBUG:   Frame Samples : 4800

10:48 T:2970651456   DEBUG:   Frame Size    : 4

10:48 T:2970651456   DEBUG: CSoftAE::InternalOpenSink - Internal Buffer Size: 9600

10:48 T:2571168576   DEBUG: CDVDPlayerAudio:: synctype set to 1: skip/duplicate

10:48 T:2781215552   DEBUG: CDVDPlayer::HandleMessages - player started 1

10:49 T:2781215552   DEBUG: set caching from pvr to done. audio (1) = 11. video (1) = 13

10:49 T:2781215552   DEBUG: CDVDPlayer::SetCaching - caching state 0

10:49 T:2571168576   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:178.973000, should be:-194550.122666, error:-194729.095666

10:49 T:2530503488   DEBUG: ffmpeg[96D46B40]: [h264] Increasing reorder buffer to 1

10:49 T:2530503488   DEBUG: ffmpeg[96D46B40]: [h264] Increasing reorder buffer to 2

10:49 T:2970651456   DEBUG: CSoftAE::Run - Sink restart flagged

10:49 T:2970651456    INFO: CSoftAE::InternalOpenSink - RAW passthrough enabled

10:49 T:2970651456    INFO: CSoftAE::InternalOpenSink - keeping old sink with : AE_FMT_AC3, FL,FR, 48000hz

10:49 T:2970651456   DEBUG: CSoftAE::InternalOpenSink - Internal Buffer Size: 9600

10:49 T:2530503488   ERROR: ffmpeg[96D46B40]: [h264] mmco: unref short failure

10:49 T:2530503488   ERROR: Previous line repeats 1 times.

10:49 T:2530503488   DEBUG: VAAPI - destroying surface 0x4000013

10:49 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:50 T:2530503488 WARNING: Previous line repeats 2 times.

10:50 T:2530503488   DEBUG: OutputPicture - dropped in output

10:50 T:2571168576   DEBUG: Previous line repeats 3 times.

10:50 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:51 T:2530503488   DEBUG: OutputPicture - dropped in output

10:51 T:2571168576   DEBUG: Previous line repeats 9 times.

10:51 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:51 T:2530503488   DEBUG: OutputPicture - dropped in output

10:51 T:2571168576   DEBUG: Previous line repeats 1 times.

10:51 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:51 T:2530503488   DEBUG: OutputPicture - dropped in output

10:52 T:2571168576   DEBUG: Previous line repeats 15 times.

10:52 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:52 T:2530503488   DEBUG: OutputPicture - dropped in output

10:52 T:2571168576   DEBUG: Previous line repeats 13 times.

10:52 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:52 T:2530503488   DEBUG: OutputPicture - dropped in output

10:52 T:2530503488   DEBUG: Previous line repeats 1 times.

10:52 T:2530503488   DEBUG: CPullupCorrection: detected pattern of length 2: 0.00 40000.00, frameduration: 20000.000000

10:52 T:2530503488   DEBUG: OutputPicture - dropped in output

10:52 T:2571168576   DEBUG: Previous line repeats 1 times.

10:52 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:52 T:2530503488   DEBUG: OutputPicture - dropped in output

10:53 T:2571168576   DEBUG: Previous line repeats 9 times.

10:53 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:53 T:2530503488   DEBUG: OutputPicture - dropped in output

10:53 T:2571168576   DEBUG: Previous line repeats 3 times.

10:53 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:53 T:2530503488   DEBUG: OutputPicture - dropped in output

10:53 T:2571168576   DEBUG: Previous line repeats 9 times.

10:53 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:54 T:2530503488   DEBUG: OutputPicture - dropped in output

10:54 T:2571168576   DEBUG: Previous line repeats 4 times.

10:54 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:54 T:2530503488   DEBUG: OutputPicture - dropped in output

10:55 T:2571168576   DEBUG: Previous line repeats 30 times.

10:55 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:55 T:2530503488   DEBUG: OutputPicture - dropped in output

10:56 T:2571168576   DEBUG: Previous line repeats 14 times.

10:56 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:56 T:2530503488   DEBUG: OutputPicture - dropped in output

10:56 T:2571168576   DEBUG: Previous line repeats 13 times.

10:56 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:56 T:2530503488   DEBUG: OutputPicture - dropped in output

10:56 T:2571168576   DEBUG: Previous line repeats 3 times.

10:56 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:56 T:2530503488   DEBUG: OutputPicture - dropped in output

10:57 T:2571168576   DEBUG: Previous line repeats 13 times.

10:57 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:57 T:2530503488 WARNING: Previous line repeats 1 times.

10:57 T:2530503488   DEBUG: OutputPicture - dropped in output

10:58 T:2571168576   DEBUG: Previous line repeats 13 times.

10:58 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:58 T:2530503488   DEBUG: OutputPicture - dropped in output

10:58 T:2571168576   DEBUG: Previous line repeats 14 times.

10:58 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:58 T:2530503488   DEBUG: OutputPicture - dropped in output

10:58 T:2571168576   DEBUG: Previous line repeats 1 times.

10:58 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:59 T:2530503488   DEBUG: OutputPicture - dropped in output

10:59 T:2571168576   DEBUG: Previous line repeats 8 times.

10:59 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:59 T:2530503488   DEBUG: OutputPicture - dropped in output

10:59 T:2571168576   DEBUG: Previous line repeats 5 times.

10:59 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

10:59 T:2530503488   DEBUG: OutputPicture - dropped in output

11:00 T:2571168576   DEBUG: Previous line repeats 14 times.

11:00 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

11:00 T:2530503488   DEBUG: OutputPicture - dropped in output

11:00 T:2571168576   DEBUG: Previous line repeats 13 times.

11:00 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

11:00 T:2530503488   DEBUG: OutputPicture - dropped in output

11:00 T:2571168576   DEBUG: Previous line repeats 2 times.

11:00 T:2571168576 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

11:01 T:3038050048 WARNING: Previous line repeats 1 times.

11:01 T:3038050048   DEBUG: Keyboard: scancode: 09, sym: 001b, unicode: 001b, modifier: 0

11:01 T:3038050048   DEBUG: OnKey: escape (f01b) pressed, action is Fullscreen

11:01 T:3038050048   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate

11:01 T:2530503488   DEBUG: OutputPicture - dropped in output

11:01 T:3038050048   DEBUG: Previous line repeats 6 times.

vainfo:

Code:
error: can't connect to X server!

libva info: VA-API version 0.34.0

libva info: va_getDriverName() returns 0

libva info: Trying to open /usr/lib/i386-linux-gnu/dri/i965_drv_video.so

libva info: Found init function __vaDriverInit_0_34

libva info: va_openDriver() returns 0

vainfo: VA-API version: 0.34 (libva 1.2.0.pre1)

vainfo: Driver version: Intel i965 driver - 1.1.0.pre1

vainfo: Supported profile and entrypoints

      VAProfileNone                   : VAEntrypointVideoProc

      VAProfileMPEG2Simple            : VAEntrypointVLD

      VAProfileMPEG2Simple            : VAEntrypointEncSlice

      VAProfileMPEG2Main              : VAEntrypointVLD

      VAProfileMPEG2Main              : VAEntrypointEncSlice

      VAProfileH264Baseline           : VAEntrypointVLD

      VAProfileH264Baseline           : VAEntrypointEncSlice

      VAProfileH264Main               : VAEntrypointVLD

      VAProfileH264Main               : VAEntrypointEncSlice

      VAProfileH264High               : VAEntrypointVLD

      VAProfileH264High               : VAEntrypointEncSlice

      VAProfileVC1Simple              : VAEntrypointVLD

      VAProfileVC1Main                : VAEntrypointVLD

      VAProfileVC1Advanced            : VAEntrypointVLD

With deinterlacing disabled or with XBMC-Main ppa: no drops at all
any ideas ?

regards gebnix

I have the exact same system as you have. Make sure, that you did the settings exactly as they are described in the howto.

For the Celeron 847 it is quite normal, that you get approx 1 fps drop when the OSD is on, but when I see it right you only have 37fps on total. There are no drops at all when running in fullscreen with 1080i50 content. Sadly you cut the important lines from this logfile, so I cannot see what I want to see.

Make sure, that you have set Scaling to "bilinear" and save this for all files.

thanks for your fast reply.
I made all settings like described in the first post.
Bilinear scaling is on.
Which lines from my xbmc.log do you want to see ?

here is a full xbmc.log with debug: http://paste.ubuntu.com/5745589/

Can you completely remove Boblight?

If you want, you can also join #xbmc-xvba

ok, deactivating boblight-Addon did it.
I switched to boblight-X11 and everything is working fine now.

Pause / FFWD is now fixed in frodo-vpp branch.

fritsch

TheTroll

TheTroll

TheTroll

fritsch

TheTroll

fritsch

gebnix

fritsch

gebnix

gebnix

fritsch

fritsch

gebnix

fritsch