Skip to content

HTTPS clone URL

Subversion checkout URL

You can clone with
or
.
Download ZIP

Loading…

MPEG2 Issues playing DVD's #49

Closed
newphreak opened this Issue · 25 comments

4 participants

@newphreak

-Deinterlacing screws up the top and bottom and flickers allot.
http://www.youtube.com/watch?v=XAuIAJz8jSI&feature=youtu.be
Settings:
http://i.imgur.com/T3qai.jpg
-Setting "Video scaling method" to auto results in CRAPPY fps and choppy playback.
The video is 25fps, so it should be playing 50fps or 25fps, not whatever it's doing here:
http://i.imgur.com/1cWFw.jpg

Cannot provide samples as .vob plays perfect.
This can only be replicated by using a dvd, so I am assuming .vob is played as video, while .iso and dvd is played as dvd. Thus being treated totally different.

@FernetMenta
Owner

Are you saying "video scaling methds" or "deinterlace method" to auto results in choppy playback?

@newphreak

"video scaling method", It was set to Bilinear, so fritsch told me to put that to auto too, but that made my videoplayback choppy.

@FernetMenta
Owner

Your DVD is SD video, most likely interlaced. In this case scaling method "auto" means lancos3. Maybe your ION is simply maxed out.
The flickering top/bottom lines is a different issue.

@newphreak

Could be, we could run some tests on it some other day. And yes, it's two different issues.

@fritsch

@newphreak:
Setting the last setting to Auto was just to make sure that you (testing with your Fusion) absolutely have the same settings as I have with mine. As stated, I tried two different DVDs and could not reproduce what you are seeing, that makes me still wonder, as we have the some codebase and the same hardware.

@newphreak

Update:
Setting the "video scaling method" to auto does the same on fusion. But it seems the dvdplayer tries to play it at 50fps. If i try playing the .vob it works perfectly, and plays at 25fps. With the .iso it plays at all from 38-50fps, jumping all around the chart. Plus the flickering top/bottom. This is also gone when you play a .vob.
So the dvdplayer needs some updating and cleanup I guess.
Logs of .iso fuckup and .vob playback:
http://sprunge.us/KSQe

@FernetMenta
Owner

Those DVDs I have got are playing fine. If you want me to investigate, you have to point me to an ISO which does not.

@szymonciow

I have the same issue. I think the problem is with deinterlacing, I have tested on Zotac ION.
I have investigated on latest commits from adaffe4 to the latest one.
I suggest 1e53dcb commit breaks deinterlacing support on Nvidia ION (or f3a7410 commit).
Latest one without issue: d2b0b50.

My config: Openelec r11501, own pvr build from git, manually added FernetMenta's xbmc code .
Tested on sat channels EurosportHD, Canal+HD and the others, directly from xvdr addon or from disk (recorded in VDR-1.7.28).
Behaviors: many frame drops on all deinterlacing settings, unstable fps around 30-40 fps

@FernetMenta
Owner

You are not talking about DVDs, right? Can you post a debug log?

@szymonciow

That's right.
Finally I have cloned xbmc once again, prepared Openelec distro - issue occurred.

Than manually I reverted only 1e53dcb commit.
Sat streams play fine with xvdr plugin from vdr. I will prepare logs but it takes a while
or longer

@FernetMenta
Owner

Interesting. Could you try only adding this line back if it would break again?
swa.override_redirect = False;

@szymonciow

I hope it is enough:

23:28:13 T:2952231744 DEBUG: AddOnLog: VDR XVDR Client: changing to channel 14 (priority 55)
23:28:14 T:3012950336 DEBUG: ------ Window Init (DialogBusy.xml) ------
23:28:14 T:3012950336 DEBUG: LIRC: Update - NEW at 41409:160 0 KEY_OK_UP devinput (KEY_OK_UP)
23:28:14 T:2952231744 DEBUG: PVRFile - Open - playback has started on filename pvr://channels/tv/All TV channels/12.pvr
23:28:14 T:2952231744 NOTICE: Creating Demuxer
23:28:14 T:2952231744 DEBUG: CDVDPlayer::SetCaching - caching state 2
23:28:14 T:2952231744 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED
23:28:14 T:2952231744 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED
23:28:14 T:3012950336 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
23:28:14 T:3012950336 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
23:28:14 T:3012950336 DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/All TV channels/12.pvr)
23:28:14 T:3012950336 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
23:28:14 T:3012950336 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
23:28:14 T:3012950336 DEBUG: Building didl for object 'pvr://channels/tv/All TV channels/12.pvr'
23:28:15 T:2952231744 DEBUG: AddOnLog: VDR XVDR Client: DemuxReset
23:28:15 T:2952231744 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added stream 0:3210 with codec_id 86016
23:28:15 T:2952231744 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added stream 1:3202 with codec_id 86019
23:28:15 T:2952231744 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added stream 2:3000 with codec_id 28
23:28:15 T:2952231744 DEBUG: CDVDDemuxPVRClient::UpdateStreams(): update stream 0:3210 with codec_id 86016
23:28:15 T:2952231744 DEBUG: CDVDDemuxPVRClient::UpdateStreams(): update stream 1:3202 with codec_id 86019
23:28:15 T:2952231744 DEBUG: CDVDDemuxPVRClient::UpdateStreams(): update stream 2:3000 with codec_id 28
23:28:15 T:2952231744 NOTICE: Opening audio stream: 0 source: 256
23:28:15 T:2952231744 NOTICE: Finding audio codec for: 86016
23:28:15 T:2952231744 DEBUG: DllAvUtilBase: Using libavutil system library
23:28:15 T:2952231744 DEBUG: FactoryCodec - Audio: passthrough - Opening
23:28:15 T:2952231744 DEBUG: FactoryCodec - Audio: passthrough - Failed
23:28:15 T:2952231744 DEBUG: FactoryCodec - Audio: libmad - Opening
23:28:15 T:2952231744 DEBUG: SECTION:LoadDLL(libmad.so.0)
23:28:15 T:2952231744 DEBUG: Loading: libmad.so.0
23:28:15 T:2952231744 DEBUG: FactoryCodec - Audio: libmad - Opened
23:28:15 T:2952231744 DEBUG: CDVDPlayerAudio: Sample rate changed, checking for passthrough
23:28:15 T:2952231744 DEBUG: DllAvUtilBase: Using libavutil system library
23:28:15 T:2952231744 DEBUG: FactoryCodec - Audio: passthrough - Opening
23:28:15 T:2952231744 DEBUG: FactoryCodec - Audio: passthrough - Failed
23:28:15 T:2952231744 DEBUG: FactoryCodec - Audio: libmad - Opening
23:28:15 T:2952231744 DEBUG: FactoryCodec - Audio: libmad - Opened
23:28:15 T:2952231744 NOTICE: Creating audio thread
23:28:15 T:2648664896 NOTICE: Thread CDVDPlayerAudio start, auto delete: false
23:28:15 T:2648664896 NOTICE: running thread: CDVDPlayerAudio::Process()
23:28:15 T:2648664896 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(62784608578.000000, 1)
23:28:15 T:2648664896 DEBUG: CDVDPlayerAudio: Sample rate changed, checking for passthrough
23:28:15 T:2648664896 DEBUG: DllAvUtilBase: Using libavutil system library
23:28:15 T:2648664896 DEBUG: FactoryCodec - Audio: passthrough - Opening
23:28:15 T:2648664896 DEBUG: FactoryCodec - Audio: passthrough - Failed
23:28:15 T:2648664896 DEBUG: FactoryCodec - Audio: libmad - Opening
23:28:15 T:2648664896 DEBUG: FactoryCodec - Audio: libmad - Opened
23:28:15 T:2648664896 NOTICE: Creating audio stream (codec id: 86016, channels: 2, sample rate: 48000, no pass-through)
23:28:15 T:2648664896 INFO: CSoftAE::MakeStream - AE_FMT_FLOAT, 48000, FL,FR
23:28:15 T:3012856640 DEBUG: CSoftAE::Run - Sink restart flagged
23:28:15 T:3012856640 INFO: CSoftAE::InternalOpenSink - sink incompatible, re-starting
23:28:15 T:2952231744 NOTICE: Opening video stream: 2 source: 256
23:28:15 T:2952231744 NOTICE: Creating video codec with codec id: 28
23:28:15 T:2952231744 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no OpenMax:no VDPAU:yes VAAPI:no
23:28:15 T:2952231744 DEBUG: FactoryCodec - Video: - Opening
23:28:15 T:2952231744 DEBUG: DllAvUtilBase: Using libavutil system library
23:28:15 T:2952231744 DEBUG: DllAvCodec: Using libavcodec system library
23:28:15 T:2952231744 DEBUG: DllSwScale: Using libswscale system library
23:28:15 T:2952231744 DEBUG: DllAvFilter: Using libavfilter system library
23:28:15 T:2952231744 NOTICE: CDVDVideoCodecFFmpeg::Open() Creating VDPAU(1920x1080, 28)
23:28:15 T:2952231744 DEBUG: DllAvUtilBase: Using libavutil system library
23:28:15 T:3012856640 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=NVidia,DEV=0"
23:28:15 T:3012856640 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=NVidia,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02"
23:28:15 T:3012856640 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
23:28:15 T:3012856640 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
23:28:15 T:3012856640 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 512, periods 16, bufferSize 8192
23:28:15 T:3012856640 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 512, periods 16, bufferSize 8192
23:28:15 T:3012856640 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 171 ms
23:28:15 T:3012856640 INFO: CSoftAE::InternalOpenSink - ALSA Initialized:
23:28:15 T:3012856640 INFO: Output Device : hdmi:CARD=NVidia,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02
23:28:15 T:3012856640 INFO: Sample Rate : 48000
23:28:15 T:3012856640 INFO: Sample Format : AE_FMT_S32NE
23:28:15 T:3012856640 INFO: Channel Count : 2
23:28:15 T:3012856640 INFO: Channel Layout: FL,FR
23:28:15 T:3012856640 INFO: Frames : 512
23:28:15 T:3012856640 INFO: Frame Samples : 1024
23:28:15 T:3012856640 INFO: Frame Size : 8
23:28:15 T:3012856640 DEBUG: CSoftAE::Initialize - Using speaker layout: 5.0
23:28:15 T:3012856640 INFO: CAEWAVLoader::Initialize - Sound Loaded: /storage/.xbmc/addons/skin.transparency/sounds/click.wav
23:28:15 T:3012856640 DEBUG: AERemap: Downmix normalization is enabled
23:28:15 T:3012856640 INFO: ==[Downmix Matrix]==
23:28:15 T:3012856640 INFO: FL = FL(1)
23:28:15 T:3012856640 INFO: FR = FR(1)
23:28:15 T:3012856640 INFO: ====================
23:28:15 T:3012856640 INFO: CAEWAVLoader::Initialize - Sound Loaded: /storage/.xbmc/addons/skin.transparency/sounds/next.wav
23:28:15 T:3012856640 DEBUG: AERemap: Downmix normalization is enabled
23:28:15 T:3012856640 INFO: ==[Downmix Matrix]==
23:28:15 T:3012856640 INFO: FL = FL(1)
23:28:15 T:3012856640 INFO: FR = FR(1)
23:28:15 T:3012856640 INFO: ====================
23:28:16 T:3012856640 INFO: CAEWAVLoader::Initialize - Sound Loaded: /storage/.xbmc/addons/skin.transparency/sounds/back.wav
23:28:16 T:3012856640 DEBUG: AERemap: Downmix normalization is enabled
23:28:16 T:3012856640 INFO: ==[Downmix Matrix]==
23:28:16 T:3012856640 INFO: FL = FL(1)
23:28:16 T:3012856640 INFO: FR = FR(1)
23:28:16 T:3012856640 INFO: ====================
23:28:16 T:3012856640 INFO: CAEWAVLoader::Initialize - Sound Loaded: /storage/.xbmc/addons/skin.transparency/sounds/screenshot.wav
23:28:16 T:3012856640 DEBUG: AERemap: Downmix normalization is enabled
23:28:16 T:3012856640 INFO: ==[Downmix Matrix]==
23:28:16 T:3012856640 INFO: FL = FL(1)
23:28:16 T:3012856640 INFO: FR = FR(1)
23:28:16 T:3012856640 INFO: ====================
23:28:16 T:3012856640 INFO: CAEWAVLoader::Initialize - Sound Loaded: /storage/.xbmc/addons/skin.transparency/sounds/pop.wav
23:28:16 T:3012856640 DEBUG: AERemap: Downmix normalization is enabled
23:28:16 T:3012856640 INFO: ==[Downmix Matrix]==
23:28:16 T:3012856640 INFO: FL = FL(1)
23:28:16 T:3012856640 INFO: FR = FR(1)
23:28:16 T:3012856640 INFO: ====================
23:28:16 T:3012856640 DEBUG: AERemap: Downmix normalization is enabled
23:28:16 T:3012856640 INFO: ==[Downmix Matrix]==
23:28:16 T:3012856640 INFO: FL = FL(1)
23:28:16 T:3012856640 INFO: FR = FR(1)
23:28:16 T:3012856640 INFO: ====================
23:28:16 T:3012856640 INFO: ==[Downmix Matrix]==
23:28:16 T:3012856640 INFO: FL = FL(1)
23:28:16 T:3012856640 INFO: FR = FR(1)
23:28:16 T:3012856640 INFO: ====================
23:28:16 T:2648664896 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
23:28:16 T:3012856640 INFO: CAESinkALSA - ALSA: pcm_hw.c:587:(snd_pcm_hw_start) SNDRV_PCM_IOCTL_START failed (-32): Broken pipe
23:28:16 T:2952231744 NOTICE: vdp_device = 0x00000001 vdp_st = 0x00000000
23:28:16 T:2952231744 NOTICE: VDPAU Decoder capabilities:
23:28:16 T:2952231744 NOTICE: name level macbs width height
23:28:16 T:2952231744 NOTICE: ------------------------------------
23:28:16 T:2952231744 NOTICE: MPEG1 0 8192 2048 2048
23:28:16 T:2952231744 NOTICE: MPEG2_SIMPLE 3 8192 2048 2048
23:28:16 T:2952231744 NOTICE: MPEG2_MAIN 3 8192 2048 2048
23:28:16 T:2952231744 NOTICE: H264_MAIN 41 8190 2032 2048
23:28:16 T:2952231744 NOTICE: H264_HIGH 41 8190 2032 2048
23:28:16 T:2952231744 NOTICE: VC1_SIMPLE 1 8190 2048 2048
23:28:16 T:2952231744 NOTICE: VC1_MAIN 2 8190 2048 2048
23:28:16 T:2952231744 NOTICE: VC1_ADVANCED 4 8190 2048 2048
23:28:16 T:2952231744 NOTICE: ------------------------------------
23:28:16 T:2952231744 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_NOISE_REDUCTION
23:28:16 T:2952231744 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_SHARPNESS
23:28:16 T:2952231744 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL
23:28:16 T:2952231744 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL_SPATIAL
23:28:16 T:2952231744 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_INVERSE_TELECINE
23:28:16 T:2952231744 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 (VDPAU acceleration)
23:28:16 T:2952231744 DEBUG: FactoryCodec - Video: ff-h264_vdpau-vdpau - Opened
23:28:16 T:2708454208 NOTICE: Thread CVideoReferenceClock start, auto delete: false
23:28:16 T:2708454208 DEBUG: CVideoReferenceClock: Setting up GLX
23:28:16 T:2708454208 DEBUG: CVideoReferenceClock: Detected refreshrate: 50 hertz
23:28:16 T:2952231744 DEBUG: CXBMCRenderManager::EnableBuffering - 0
23:28:16 T:2952231744 NOTICE: Creating video thread
23:28:16 T:2725239616 NOTICE: Thread CDVDPlayerVideo start, auto delete: false
23:28:16 T:2725239616 NOTICE: running thread: video_thread
23:28:16 T:2952231744 DEBUG: CDVDPlayer::HandleMessages - player started 1
23:28:16 T:2725239616 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(62785589289.000000, 0)
23:28:16 T:2725239616 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
23:28:16 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
23:28:16 T:2725239616 DEBUG: CVDPAU::SetWidthHeight Setting OutWidth: 1920 OutHeight: 1080
23:28:16 T:2725239616 NOTICE: (VDPAU) screenWidth:1920 vidWidth:1920 surfaceWidth:1920
23:28:16 T:2725239616 NOTICE: (VDPAU) screenHeight:1080 vidHeight:1080 surfaceHeight:1088
23:28:17 T:2952231744 DEBUG: set caching from pvr to done. audio (1) = 15. video (1) = 13
23:28:17 T:2952231744 DEBUG: CDVDPlayer::SetCaching - caching state 0
23:28:17 T:2952231744 ERROR: Get - failed to get stream
23:28:17 T:3012856640 DEBUG: CSoftAE::Run - Sink restart flagged
23:28:17 T:3012856640 INFO: CSoftAE::InternalOpenSink - keeping old sink with : AE_FMT_FLOAT, FL,FR, 48000hz
23:28:17 T:3012856640 DEBUG: CSoftAE::Initialize - Using speaker layout: 5.0
23:28:17 T:2648664896 DEBUG: CDVDPlayerAudio:: Discontinuity - was:62786100071.726997, should be:62784429155.643112, error:-1670916.083885
23:28:17 T:2708454208 DEBUG: CVideoReferenceClock: detected 1 vblanks, missed 5, refreshrate might have changed
23:28:17 T:2628098880 NOTICE: Thread Vdpau Output Thread start, auto delete: false
23:28:17 T:2628098880 NOTICE: COutput::OnStartup: Output Thread created
23:28:17 T:2628098880 NOTICE: VDPAU::COutput::CreateGlxContext - created context
23:28:17 T:2628098880 NOTICE: VDPAU::COutput GL interop supported
23:28:17 T:2628098880 NOTICE: VDPAU::COutput: vdpau gl interop initialized
23:28:17 T:2619706176 NOTICE: Thread Vdpau Mixer Thread start, auto delete: false
23:28:17 T:2619706176 NOTICE: CMixer::OnStartup: Output Thread created
23:28:17 T:2619706176 NOTICE: (VDPAU) Creating the video mixer
23:28:17 T:2628098880 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created
23:28:17 T:2725239616 NOTICE: Previous line repeats 3 times.
23:28:17 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
23:28:17 T:2725239616 DEBUG: ffmpeg[A26FDB40]: [h264_vdpau] Increasing reorder buffer to 1
23:28:17 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
23:28:17 T:3012950336 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
23:28:17 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
23:28:17 T:2725239616 DEBUG: ffmpeg[A26FDB40]: [h264_vdpau] Increasing reorder buffer to 2
23:28:17 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
23:28:17 T:2725239616 DEBUG: Previous line repeats 7 times.
23:28:17 T:2725239616 DEBUG: ffmpeg[A26FDB40]: [h264_vdpau] Increasing reorder buffer to 3
23:28:17 T:2628098880 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created
23:28:17 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
23:28:17 T:2628098880 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created
23:28:17 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
23:28:17 T:2628098880 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created
23:28:17 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
23:28:17 T:2628098880 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created
23:28:17 T:2628098880 NOTICE: Previous line repeats 1 times.
23:28:17 T:2628098880 NOTICE: VDPAU::COutput registered output surfaces
23:28:17 T:2725239616 NOTICE: fps: 0.000000, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080
23:28:17 T:2725239616 DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 0.00. format: VDPAU
23:28:17 T:2725239616 NOTICE: CXBMCRenderManager::ResetRenderBuffer - using 5 render buffers
23:28:17 T:2648664896 DEBUG: CDVDPlayerAudio:: synctype set to 1: skip/duplicate
23:28:17 T:3012950336 NOTICE: Using GL_TEXTURE_2D
23:28:17 T:3012950336 NOTICE: GL: Using VDPAU render method
23:28:17 T:3012950336 NOTICE: GL: NPOT texture support detected
23:28:17 T:3012950336 NOTICE: GL: Using GL_ARB_pixel_buffer_object
23:28:17 T:2725239616 DEBUG: CXBMCRenderManager::EnableBuffering - 1
23:28:17 T:2952231744 DEBUG: CDVDPlayer::HandleMessages - player started 2
23:28:18 T:2725239616 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
23:28:19 T:2648664896 WARNING: Previous line repeats 4 times.
23:28:19 T:2648664896 DEBUG: CDVDPlayerAudio:: Duplicating 1 packet(s) of 24.00 ms duration
23:28:19 T:3012950336 DEBUG: LIRC: Update - NEW at 46543:160 0 KEY_OK devinput (KEY_OK)
23:28:19 T:3012950336 DEBUG: OnKey: 11 (0b) pressed, action is Select
23:28:19 T:3012950336 DEBUG: Activating window ID: 12005
23:28:19 T:3012950336 DEBUG: ------ Window Deinit (MyPVR.xml) ------
23:28:19 T:3012950336 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
23:28:19 T:3012950336 INFO: Loading skin file: VideoFullScreen.xml
23:28:19 T:2977950528 DEBUG: AddOnLog: VDR XVDR Client: SendPing
23:28:19 T:2725239616 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
23:28:19 T:3012950336 DEBUG: LIRC: Update - NEW at 46758:160 0 KEY_OK_UP devinput (KEY_OK_UP)
23:28:19 T:2725239616 ERROR: ffmpeg[A26FDB40]: [h264_vdpau] mmco: unref short failure
23:28:19 T:2725239616 ERROR: ffmpeg[A26FDB40]: [h264_vdpau] number of reference frames (0+5) exceeds max (4; probably corrupt input), discarding one
23:28:20 T:2725239616 WARNING: CVDPAU::DrawSlice - VdpDecoderRender long decoding: 32 ms, dec: 2, proc: 2, rend: 0
23:28:20 T:2725239616 DEBUG: OutputPicture - dropped in output
23:28:20 T:2648664896 DEBUG: CDVDPlayerAudio:: Duplicating 1 packet(s) of 24.00 ms duration
23:28:20 T:2725239616 DEBUG: OutputPicture - dropped in output
23:28:20 T:2725239616 DEBUG: Previous line repeats 8 times.
23:28:20 T:2725239616 WARNING: CVDPAU::DrawSlice - VdpDecoderRender long decoding: 35 ms, dec: 3, proc: 0, rend: 0
23:28:20 T:2725239616 DEBUG: OutputPicture - dropped in output
23:28:21 T:2648664896 DEBUG: Previous line repeats 1 times.
23:28:21 T:2648664896 DEBUG: CDVDPlayerAudio:: Duplicating 1 packet(s) of 24.00 ms duration
23:28:21 T:2725239616 WARNING: CVDPAU::DrawSlice - VdpDecoderRender long decoding: 42 ms, dec: 2, proc: 0, rend: 2
23:28:21 T:2725239616 DEBUG: OutputPicture - dropped in output
23:28:21 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:21 T:2725239616 WARNING: CVDPAU::DrawSlice - VdpDecoderRender long decoding: 31 ms, dec: 3, proc: 0, rend: 0
23:28:21 T:2725239616 DEBUG: OutputPicture - dropped in output
23:28:21 T:2725239616 DEBUG: Previous line repeats 2 times.
23:28:21 T:2725239616 WARNING: CVDPAU::DrawSlice - VdpDecoderRender long decoding: 32 ms, dec: 2, proc: 0, rend: 0
23:28:22 T:2725239616 WARNING: CVDPAU::DrawSlice - VdpDecoderRender long decoding: 33 ms, dec: 3, proc: 0, rend: 0
23:28:22 T:2725239616 DEBUG: CPullupCorrection: detected pattern of length 2: 0.00 40000.00, frameduration: 20000.000000
23:28:22 T:2725239616 DEBUG: OutputPicture - dropped in output
23:28:22 T:2725239616 DEBUG: Previous line repeats 2 times.
23:28:22 T:2725239616 WARNING: CVDPAU::DrawSlice - VdpDecoderRender long decoding: 32 ms, dec: 3, proc: 0, rend: 0
23:28:22 T:2725239616 DEBUG: OutputPicture - dropped in output
23:28:22 T:2725239616 DEBUG: Previous line repeats 4 times.
23:28:22 T:2725239616 WARNING: CVDPAU::DrawSlice - VdpDecoderRender long decoding: 32 ms, dec: 2, proc: 0, rend: 1
23:28:22 T:2725239616 DEBUG: OutputPicture - dropped in output
23:28:23 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:23 T:2725239616 WARNING: CVDPAU::DrawSlice - VdpDecoderRender long decoding: 34 ms, dec: 2, proc: 2, rend: 0
23:28:23 T:2725239616 DEBUG: OutputPicture - dropped in output
23:28:23 T:2725239616 DEBUG: CalcFrameRate framerate was:25.000000 calculated:50.000000
23:28:23 T:2725239616 NOTICE: fps: 50.000000, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080
23:28:23 T:2725239616 DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 50.00. format: VDPAU
23:28:23 T:2725239616 NOTICE: Display resolution ADJUST : 1920x1080 @ 50.00 - Full Screen (12) (weight: 0.000)
23:28:23 T:2725239616 NOTICE: CXBMCRenderManager::ResetRenderBuffer - using 5 render buffers
23:28:23 T:3012950336 NOTICE: Using GL_TEXTURE_2D
23:28:23 T:3012950336 NOTICE: GL: Using VDPAU render method
23:28:23 T:3012950336 NOTICE: GL: NPOT texture support detected
23:28:23 T:3012950336 NOTICE: GL: Using GL_ARB_pixel_buffer_object
23:28:23 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
23:28:23 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:23 T:2725239616 DEBUG: CPullupCorrection: pattern lost on diff 20000.000000
23:28:23 T:2725239616 WARNING: CVDPAU::DrawSlice - VdpDecoderRender long decoding: 37 ms, dec: 2, proc: 0, rend: 0
23:28:23 T:2648664896 DEBUG: CDVDPlayerAudio:: Skipping 1 packet(s) of 24.00 ms duration
23:28:23 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.149473, Bufferlevel: 4
23:28:23 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:23 T:2725239616 WARNING: CVDPAU::DrawSlice - VdpDecoderRender long decoding: 33 ms, dec: 2, proc: 0, rend: 2
23:28:23 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.189453, Bufferlevel: 4
23:28:23 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:23 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.189447, Bufferlevel: 4
23:28:23 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:23 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.189438, Bufferlevel: 4
23:28:23 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:23 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.169431, Bufferlevel: 4
23:28:23 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.149428, Bufferlevel: 4
23:28:23 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.129426, Bufferlevel: 4
23:28:23 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:23 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.069408, Bufferlevel: 4
23:28:23 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.069400, Bufferlevel: 4
23:28:23 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089393, Bufferlevel: 3
23:28:24 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089392, Bufferlevel: 4
23:28:24 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.109390, Bufferlevel: 4
23:28:24 T:2725239616 DEBUG: Previous line repeats 2 times.
23:28:24 T:2725239616 WARNING: CVDPAU::DrawSlice - VdpDecoderRender long decoding: 35 ms, dec: 3, proc: 0, rend: 0
23:28:24 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089389, Bufferlevel: 4
23:28:24 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:24 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.049391, Bufferlevel: 4
23:28:24 T:3012950336 DEBUG: LIRC: Update - NEW at 51915:184 0 KEY_TEXT devinput (KEY_TEXT)
23:28:24 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.069394, Bufferlevel: 4
23:28:24 T:3012950336 DEBUG: OnKey: 250 (fa) pressed, action is CodecInfo
23:28:24 T:3012950336 DEBUG: LIRC: Update - NEW at 51942:184 0 KEY_TEXT_UP devinput (KEY_TEXT_UP)
23:28:24 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089398, Bufferlevel: 4
23:28:24 T:2725239616 WARNING: CVDPAU::DrawSlice - VdpDecoderRender long decoding: 31 ms, dec: 2, proc: 0, rend: 2
23:28:24 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089406, Bufferlevel: 4
23:28:24 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:24 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089409, Bufferlevel: 4
23:28:24 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:24 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.109419, Bufferlevel: 4
23:28:25 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.069426, Bufferlevel: 4
23:28:25 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089433, Bufferlevel: 4
23:28:25 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089441, Bufferlevel: 4
23:28:25 T:2977950528 DEBUG: AddOnLog: VDR XVDR Client: SendPing
23:28:25 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089450, Bufferlevel: 4
23:28:25 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089459, Bufferlevel: 4
23:28:25 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089468, Bufferlevel: 4
23:28:25 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:25 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.069473, Bufferlevel: 4
23:28:25 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.049498, Bufferlevel: 4
23:28:25 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.069509, Bufferlevel: 4
23:28:25 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089520, Bufferlevel: 4
23:28:25 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089531, Bufferlevel: 4
23:28:25 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089543, Bufferlevel: 4
23:28:26 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089554, Bufferlevel: 4
23:28:26 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:26 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.069560, Bufferlevel: 4
23:28:26 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.049596, Bufferlevel: 4
23:28:26 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089612, Bufferlevel: 4
23:28:26 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.109624, Bufferlevel: 4
23:28:26 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:26 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089649, Bufferlevel: 4
23:28:26 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:26 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.069656, Bufferlevel: 4
23:28:26 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:26 T:2725239616 WARNING: CVDPAU::DrawSlice - VdpDecoderRender long decoding: 52 ms, dec: 2, proc: 0, rend: 2
23:28:26 T:3012950336 DEBUG: LIRC: Update - NEW at 54256:184 0 KEY_TEXT devinput (KEY_TEXT)
23:28:26 T:3012950336 DEBUG: OnKey: 250 (fa) pressed, action is CodecInfo
23:28:26 T:3012950336 DEBUG: LIRC: Update - NEW at 54300:184 0 KEY_TEXT_UP devinput (KEY_TEXT_UP)
23:28:26 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.069693, Bufferlevel: 4
23:28:27 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089705, Bufferlevel: 4
23:28:27 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:27 T:2725239616 WARNING: CVDPAU::DrawSlice - VdpDecoderRender long decoding: 36 ms, dec: 3, proc: 0, rend: 0
23:28:27 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.089739, Bufferlevel: 4
23:28:27 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.109751, Bufferlevel: 4
23:28:27 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:27 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.109757, Bufferlevel: 4
23:28:27 T:2725239616 DEBUG: Previous line repeats 1 times.
23:28:27 T:2725239616 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.129765, Bufferlevel: 4
23:28:27 T:2725239616 DEBUG: Previous line repeats 1 times.

@szymonciow

Finally:
"swa.override_redirect = False;" gives many drops, how describes above.

"swa.override_redirect = fullscreen ? True : False;" gives smooth picture in fullscreen.

I tested twice.

@FernetMenta
Owner

Thanks a lot for this test. I need to test for a window manager then.

@FernetMenta
Owner

@szymonciow
Would you like to test if it's fixed in current master?

@szymonciow

Definitely. Today I'll prepare new distro and I'll make some tests.

@szymonciow

ION are tested.
The same problem in current master, unstable FPS on all de-interlacing settings. Below log fragment.

20:04:40 T:3012876608 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(OSDVideoSettings)
20:04:40 T:3012876608 DEBUG: ExecuteXBMCAction : To ActivateWindow(OSDVideoSettings)
20:04:40 T:3012876608 DEBUG: Activating window ID: 10123
20:04:40 T:3012876608 DEBUG: ------ Window Init (VideoOSDSettings.xml) ------
20:04:40 T:3012876608 INFO: Loading skin file: VideoOSDSettings.xml
20:04:40 T:3012876608 DEBUG: LIRC: Update - NEW at 256084:160 0 KEY_OK_UP devinput (KEY_OK_UP)
20:04:42 T:3012876608 DEBUG: LIRC: Update - NEW at 257527:160 0 KEY_OK devinput (KEY_OK)
20:04:42 T:3012876608 DEBUG: OnKey: 11 (0b) pressed, action is Select
20:04:42 T:2969557824 DEBUG: PVR - SaveCurrentChannelSettings - persisting custom channel settings for channel 'EUROSPORT HD PL'
20:04:42 T:2636618560 NOTICE: fps: 25.000000, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080
20:04:42 T:2636618560 DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 25.00. format: VDPAU
20:04:42 T:2636618560 NOTICE: Display resolution ADJUST : 1920x1080 @ 50.00 - Full Screen (12) (weight: 0.000)
20:04:42 T:2636618560 NOTICE: CXBMCRenderManager::ResetRenderBuffer - using 5 render buffers
20:04:42 T:3012876608 NOTICE: Using GL_TEXTURE_2D
20:04:42 T:3012876608 NOTICE: GL: Using VDPAU render method
20:04:42 T:3012876608 NOTICE: GL: NPOT texture support detected
20:04:42 T:3012876608 NOTICE: GL: Using GL_ARB_pixel_buffer_object
20:04:42 T:2636618560 DEBUG: CPullupCorrection: pattern lost on diff 0.000000
20:04:42 T:2621954880 WARNING: COutput::ProcessReturnPicture - pic not found
20:04:42 T:2636618560 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
20:04:42 T:3012876608 DEBUG: LIRC: Update - NEW at 257763:160 0 KEY_OK_UP devinput (KEY_OK_UP)
20:04:43 T:2636618560 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.130000, Bufferlevel: 4
20:04:43 T:2636618560 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.210000, Bufferlevel: 4
20:04:43 T:2636618560 DEBUG: Previous line repeats 2 times.
20:04:43 T:2636618560 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.250000, Bufferlevel: 4
20:04:43 T:2636618560 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.290000, Bufferlevel: 4
20:04:43 T:2636618560 DEBUG: Previous line repeats 3 times.
20:04:43 T:2636618560 WARNING: CVDPAU::DrawSlice - VdpDecoderRender long decoding: 32 ms, dec: 1, proc: 1, rend: 1
20:04:43 T:2636618560 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.330000, Bufferlevel: 4
20:04:43 T:2636618560 DEBUG: Previous line repeats 2 times.
20:04:43 T:2636618560 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.370000, Bufferlevel: 4
20:04:44 T:2636618560 DEBUG: Previous line repeats 7 times.
20:04:44 T:2636618560 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.350000, Bufferlevel: 4
20:04:44 T:2636618560 DEBUG: Previous line repeats 3 times.

@FernetMenta
Owner

Could you please post a full debug log.

@FernetMenta
Owner

I have fixed the method of detecting a window manager. Hopefully it works now :)

@szymonciow

My new Openelec compilation already done.
I have tested many channels from xvdr and it's works fine in "VDPAU - Bob", even some SD channels not working properly earlier
Many thanks!.
BTW I have got some general questions about ION and XBMC.
The others deinterlacing settings, like spatial don't give enough performance but vdpautest does.
Is this XBMC issue or build-in codecs or something else?
What about general performance in viewing misc screens (menus) of XBMC?
I think isn't quite good on ION when there is a video background. Atom or Nvidia ION causes the problems in viewing?
On my other Intel SandyBridge (G530) platform the performance is quite better but also not enough.
I have investigated it depends also on skin. Some skins like Transparency are better other worse (Confluence).
Some screens better other worse. Screen "Info" doesn't decrease performance, "CodecInfo" also but "Playlist" does.
Is there a chance to improve this matter? My idea is to prepare "special" kind of light skin, but it takes long time :)

@FernetMenta
Owner

All XBMC rendering is done by OpenGL which eats resources too. vdpautest does not consider other tasks the GPU has to do as well. Some skins costlier to render than others and I think a ION1 can't render a movie in the background of the menu.
Recently I did some tests on Intel graphics: disappointing.
Personally I prefer NVidia systems. E.g. a Zotac ID 80 (NVidia gt520m) plays everything fine. I am going to build me a box with a Zotac D2700-itx which is basically the same as ID80 but has a pci-e slot for a tv card.

@newphreak

This issue is also cured in mainline, it does not flicker anymore. However, I vaguely remember us discussing how cropping was done on deinterlaced materiale to cut away bad bottom/top of video, am I right that this function is completly missing in the dvdplayer? Because that's all i miss now, but having those small black partial lines doesn't do any harm since they are not flickering anymore.
http://i.imgur.com/llwTI.jpg
half top left horisontally, and 2/3 bottom right horisontally.

So if this is nothing you want to implement, you can safely close this issue.

@FernetMenta
Owner

There might be cases where it would still flicker if not cropped. Let's keep this open though not at a high priority.

@newphreak

this should be fixed and merged now i believe

@newphreak newphreak closed this
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Something went wrong with that request. Please try again.