v15 VDR backend video stuttering on KODI but not on VLC
boexli
Junior Member
Posts: 11
#1
2015-08-08, 20:21
Hi Guys,

It has been a while that I have tried to fix this issues by myself. Unfortunately I have not had any success.
Let me explain:

I have a VDR server with DVD S and DVD c Tuners. I am using the VNSI plugin on the server to stream it to my OPENELEC INTEL NUC.

The setup works well for the majority of the channels. However there are two channel which are stuttering pretty bad whilst playing it on openelec.
However the same channels work well with VLC.

I have observed the issue also on my mac and ubuntu pc using Kodi - Kodi always stutters whilst VLC works fine - Therefore I would consider this not an Openelec related issue but rather a KODI / VNSI one.

I do not expect an how to fix tit advice (of course I take it - if it exists) but rather a way to get to the source of this.

Thanks Nick
Find
Reply
FernetMenta
Retired Team-Kodi Member
Posts: 6,810
#2
2015-08-08, 20:33
As a first step please post a debug log. Can you name those 2 channels?
Find
Reply
boexli
Junior Member
Posts: 11
#3
2015-08-09, 11:07
Ok the channels are ORF 1 HD and ORF 2 HD from the VDR DVB-C tuner - there is maybe also an issue with BBC HD but that is not as bad as the two frist ones. All other channels VDR DVB-C and DVB-S, SD and HD are working fine .

Thanks Nick


Code:
——————————————————————————— cut ——————————————————————
10:42:13 T:140735286735616   DEBUG: ------ Window Init (DialogSeekBar.xml) ------
10:42:13 T:4465238016   DEBUG: CDVDPlayer::CheckContinuity - resync backward :2, prev:23735315927.000000, curr:23735295927.000000, diff:-20000.000000
10:42:13 T:4465238016   DEBUG: set caching from pvr to done. audio (1) = 0. video (1) = 100
10:42:13 T:4465238016   DEBUG: CDVDPlayer::SetCaching - caching state 0
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed - audio stream stalled, triggering re-sync
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(23735255927.000000, 0)
10:42:13 T:4695035904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(23734718972.000000, 1)
10:42:13 T:4695035904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: -0.010000
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(27424044728.000000, 1)
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: -0.010000
10:42:13 T:4465238016   DEBUG: audio stream stalled. start buffering
10:42:13 T:4465238016   DEBUG: CDVDPlayer::SetCaching - caching state 2
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4695035904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandleMessages - player started 1
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandleMessages - player started 2
10:42:13 T:4465238016   DEBUG: CDVDPlayer::CheckContinuity - resync backward :2, prev:27424104728.000000, curr:27424084728.000000, diff:-20000.000000
10:42:13 T:4465238016   DEBUG: set caching from pvr to done. audio (1) = 0. video (1) = 100
10:42:13 T:4465238016   DEBUG: CDVDPlayer::SetCaching - caching state 0
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(27424064728.000000, 0)
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed - audio stream stalled, triggering re-sync
10:42:13 T:4695035904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(27423491773.000000, 1)
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: -0.010000
10:42:13 T:4695035904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(31112833529.000000, 1)
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: -0.010000
10:42:13 T:4465238016   DEBUG: audio stream stalled. start buffering
10:42:13 T:4465238016   DEBUG: CDVDPlayer::SetCaching - caching state 2
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4695035904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandleMessages - player started 1
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandleMessages - player started 2
10:42:13 T:4465238016   DEBUG: CDVDPlayer::CheckContinuity - resync backward :2, prev:31112893529.000000, curr:31112873529.000000, diff:-20000.000000
10:42:13 T:4465238016   DEBUG: set caching from pvr to done. audio (1) = 0. video (1) = 100
10:42:13 T:4465238016   DEBUG: CDVDPlayer::SetCaching - caching state 0
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed - audio stream stalled, triggering re-sync
10:42:13 T:4695035904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(31112264574.000000, 1)
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(31112853529.000000, 0)
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4695035904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: -0.010000
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(34801622330.000000, 1)
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
10:42:13 T:4465238016   DEBUG: audio stream stalled. start buffering
10:42:13 T:4465238016   DEBUG: CDVDPlayer::SetCaching - caching state 2
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4695035904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandleMessages - player started 1
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandleMessages - player started 2
10:42:13 T:4465238016   DEBUG: CDVDPlayer::CheckContinuity - resync backward :2, prev:34801682330.000000, curr:34801662330.000000, diff:-20000.000000
10:42:13 T:4465238016   DEBUG: set caching from pvr to done. audio (1) = 0. video (1) = 100
10:42:13 T:4465238016   DEBUG: CDVDPlayer::SetCaching - caching state 0
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed - audio stream stalled, triggering re-sync
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(34801642330.000000, 0)
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4695035904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: -0.010000
10:42:13 T:4695035904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(34801037375.000000, 1)
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: -0.010000
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(38490411131.000000, 1)
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: -0.010000
10:42:13 T:4465238016   DEBUG: audio stream stalled. start buffering
10:42:13 T:4465238016   DEBUG: CDVDPlayer::SetCaching - caching state 2
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandleMessages - player started 1
10:42:13 T:4695035904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandleMessages - player started 2
10:42:13 T:4465238016   DEBUG: CDVDPlayer::CheckContinuity - resync backward :2, prev:38490471131.000000, curr:38490451131.000000, diff:-20000.000000
10:42:13 T:4465238016   DEBUG: set caching from pvr to done. audio (1) = 3. video (1) = 100
10:42:13 T:4465238016   DEBUG: CDVDPlayer::SetCaching - caching state 0
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: -0.010000
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(38490431131.000000, 0)
10:42:13 T:4695035904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(38489810176.000000, 1)
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
10:42:13 T:4694499328   ERROR: ffmpeg[117D06000]: [h264] mmco: unref short failure
10:42:13 T:4694499328   ERROR: ffmpeg[117D06000]: [h264] number of reference frames (0+5) exceeds max (4; probably corrupt input), discarding one
10:42:13 T:4695035904 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -18443.913295, Bufferlevel: 2, Gain: 3688.648801
10:42:13 T:4694499328 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed - audio stream stalled, triggering re-sync
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4695035904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: -0.010000
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(42179199932.000000, 1)
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -14754.542026, Bufferlevel: 1, Gain: 3688.788801
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: -0.010000
10:42:13 T:4465238016   DEBUG: audio stream stalled. start buffering
10:42:13 T:4465238016   DEBUG: CDVDPlayer::SetCaching - caching state 2
10:42:13 T:4533866496   DEBUG: ffmpeg[10E3D5000]: 1.000000 0.000000 0.707107 0.000000 0.707107 0.000000
10:42:13 T:4533866496   DEBUG: ffmpeg[10E3D5000]: 0.000000 1.000000 0.707107 0.000000 0.000000 0.707107
10:42:13 T:4695035904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandleMessages - player started 2
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandleMessages - player started 1
10:42:13 T:4465238016   DEBUG: CDVDPlayer::CheckContinuity - resync backward :2, prev:42179259932.000000, curr:42179239932.000000, diff:-20000.000000
10:42:13 T:4465238016   DEBUG: set caching from pvr to done. audio (1) = 0. video (1) = 100
10:42:13 T:4465238016   DEBUG: CDVDPlayer::SetCaching - caching state 0
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(42179219932.000000, 0)
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed - audio stream stalled, triggering re-sync
10:42:13 T:4695035904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(42178774977.000000, 1)
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4695035904   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
10:42:13 T:4465238016   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: -0.010000
10:42:13 T:4465238016   DEBUG: audio stream stalled. start buffering
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(45867988733.000000, 1)
10:42:13 T:4465238016   DEBUG: CDVDPlayer::SetCaching - caching state 2
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
10:42:13 T:4694499328   DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -14755.109701, Bufferlevel: 1, Gain: 3688.728801
Find
Reply
FernetMenta
Retired Team-Kodi Member
Posts: 6,810
#4
2015-08-09, 12:49
please post comple debug log on pastebin.com
Find
Reply
boexli
Junior Member
Posts: 11
#5
2015-08-09, 16:27
http://pastebin.com/0ab62LNf

hope this works ...
Find
Reply
FernetMenta
Retired Team-Kodi Member
Posts: 6,810
#6
2015-08-09, 17:00
Could you record a minute of this channel and share with me?
Find
Reply
boexli
Junior Member
Posts: 11
#7
2015-08-09, 17:41
strange - playing the recording just works fine (it is stored on the VDR server though).

Do you still want me to send you a snip of it?
Find
Reply
FernetMenta
Retired Team-Kodi Member
Posts: 6,810
#8
2015-08-09, 17:48
Yes, recordings uses a different demuxer. If you share a sample I should be able to track down the issue.
Find
Reply
boexli
Junior Member
Posts: 11
#9
2015-08-10, 07:29
there we go

https://www.dropbox.com/sh/axlnm0msfa6h4...lqrYa?dl=0

Cheers Nick
Find
Reply
FernetMenta
Retired Team-Kodi Member
Posts: 6,810
#10
2015-08-11, 08:59
Thanks. Fixed in my master branch: https://github.com/FernetMenta/vdr-plugi...cf72b5bbf6
Find
Reply
boexli
Junior Member
Posts: 11
#11
2015-08-11, 19:38
installed - works just perfect! what a support! Thanks so much!
Find
Reply

Home
Search
Help
Logout Mark Read Team Forum Stats Members Help
VDR backend video stuttering on KODI but not on VLC1