(OLD) Unofficial Kodi PVR Client for HDHomeRun DVR subscribers

Kodi Community Development

Moderator: TVJunkie198

Locked
tloughr
Posts: 49
Joined: Mon Sep 12, 2011 7:05 pm

Re: Unofficial Kodi PVR Client for HDHomeRun DVR subscribers

Post by tloughr » Sun Aug 05, 2018 6:34 am

djp952 wrote:
Sun Aug 05, 2018 6:08 am
Ken.F wrote:
Sun Aug 05, 2018 5:02 am
I tried Win32 Krypton 1.3.0 on my Win7 PC but timeshifting isn't working. There is no buffer file being created in the Live TV folder and I can't skip backwards from live TV. I tried to reset discovery database in client specific actions and I stopped and started the record engine on the NAS but neither seemed to help. It finds my recordings, timers and rules. I can play back recordings. I can't timeshift.

Record engine and tuner firmware are all on version 20180802beta1. Everything is working fine with SD's HDHomeRun Android app on my Mi Box so I think that rules out a record engine or tuner hardware problem.
Sounds like it's going directly to the tuner instead of the RECORD engine. Can you verify that you do not have "Stream Live TV channels directly from tuner device(s)" enabled, and can you check the Kodi log for a message like this:

unable to generate storage engine stream URL for channel {number} - falling back to a tuner-direct stream

The channel number encoding changed in 1.3.0, something must have gone awry that I didn't exercise. Drat! It works fine on everything here :(
Direct streaming is disabled.

7:30:29.166 T:17620 NOTICE: Creating audio stream (codec id: 86019, channels: 6, sample rate: 48000, no pass-through)
07:30:29.879 T:11108 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
07:30:30.669 T:11108 WARNING: Previous line repeats 1 times.
07:30:30.669 T:11108 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 33366
07:31:13.180 T:11108 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
07:32:25.886 T:932 WARNING: Previous line repeats 11 times.

Ken.F
Posts: 2069
Joined: Fri Apr 05, 2013 9:20 am
Device ID: 1041A706, 1043EB32, 104BAD9E, 13168DC5, 1322A7AC
Location: West Rockhill, PA

Re: Unofficial Kodi PVR Client for HDHomeRun DVR subscribers

Post by Ken.F » Sun Aug 05, 2018 6:59 am

djp952 wrote:
Sun Aug 05, 2018 6:08 am
Sounds like it's going directly to the tuner instead of the RECORD engine. Can you verify that you do not have "Stream Live TV channels directly from tuner device(s)" enabled, and can you check the Kodi log for a message like this:

unable to generate storage engine stream URL for channel {number} - falling back to a tuner-direct stream

The channel number encoding changed in 1.3.0, something must have gone awry that I didn't exercise. Drat! It works fine on everything here :(
Stream live TV directly from tuner is turned off.

I found this in the log:

07:52:57.874 T:748 NOTICE: AddOnLog: HDHomeRun DVR PVR Client: OpenLiveStream: unable to generate storage engine stream URL for channel 10.1 - falling back to a tuner-direct stream

I got your PM. Do you still need the .db file?
Edit: Never mind. I e-mailed the .db file anyway.

djp952
Posts: 808
Joined: Wed Oct 01, 2008 8:46 pm
Device ID: 131EB7F7;131ED0E0
Location: Elkridge, MD

Re: Unofficial Kodi PVR Client for HDHomeRun DVR subscribers

Post by djp952 » Sun Aug 05, 2018 4:01 pm

Ken.F wrote:
Sun Aug 05, 2018 6:59 am
djp952 wrote:
Sun Aug 05, 2018 6:08 am
Sounds like it's going directly to the tuner instead of the RECORD engine. Can you verify that you do not have "Stream Live TV channels directly from tuner device(s)" enabled, and can you check the Kodi log for a message like this:

unable to generate storage engine stream URL for channel {number} - falling back to a tuner-direct stream

The channel number encoding changed in 1.3.0, something must have gone awry that I didn't exercise. Drat! It works fine on everything here :(
Stream live TV directly from tuner is turned off.

I found this in the log:

07:52:57.874 T:748 NOTICE: AddOnLog: HDHomeRun DVR PVR Client: OpenLiveStream: unable to generate storage engine stream URL for channel 10.1 - falling back to a tuner-direct stream

I got your PM. Do you still need the .db file?
Edit: Never mind. I e-mailed the .db file anyway.
File received! Looking into this now.

edit: holy cow you have a lot of HDHomeRun devices! SiliconDust's #1 customer I think!!! <bows>

djp952
Posts: 808
Joined: Wed Oct 01, 2008 8:46 pm
Device ID: 131EB7F7;131ED0E0
Location: Elkridge, MD

Re: Unofficial Kodi PVR Client for HDHomeRun DVR subscribers

Post by djp952 » Sun Aug 05, 2018 4:17 pm

tloughr wrote:
Sun Aug 05, 2018 6:34 am
djp952 wrote:
Sun Aug 05, 2018 6:08 am
Ken.F wrote:
Sun Aug 05, 2018 5:02 am
I tried Win32 Krypton 1.3.0 on my Win7 PC but timeshifting isn't working. There is no buffer file being created in the Live TV folder and I can't skip backwards from live TV. I tried to reset discovery database in client specific actions and I stopped and started the record engine on the NAS but neither seemed to help. It finds my recordings, timers and rules. I can play back recordings. I can't timeshift.

Record engine and tuner firmware are all on version 20180802beta1. Everything is working fine with SD's HDHomeRun Android app on my Mi Box so I think that rules out a record engine or tuner hardware problem.
Sounds like it's going directly to the tuner instead of the RECORD engine. Can you verify that you do not have "Stream Live TV channels directly from tuner device(s)" enabled, and can you check the Kodi log for a message like this:

unable to generate storage engine stream URL for channel {number} - falling back to a tuner-direct stream

The channel number encoding changed in 1.3.0, something must have gone awry that I didn't exercise. Drat! It works fine on everything here :(
Direct streaming is disabled.

7:30:29.166 T:17620 NOTICE: Creating audio stream (codec id: 86019, channels: 6, sample rate: 48000, no pass-through)
07:30:29.879 T:11108 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
07:30:30.669 T:11108 WARNING: Previous line repeats 1 times.
07:30:30.669 T:11108 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 33366
07:31:13.180 T:11108 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
07:32:25.886 T:932 WARNING: Previous line repeats 11 times.
Are you using ATSC (OTA) channels by chance? Channels with a subchannel number, like 2.1 or 10.1 as opposed to 462 or 530, typical for QAM/CableCard.

djp952
Posts: 808
Joined: Wed Oct 01, 2008 8:46 pm
Device ID: 131EB7F7;131ED0E0
Location: Elkridge, MD

Re: Unofficial Kodi PVR Client for HDHomeRun DVR subscribers

Post by djp952 » Sun Aug 05, 2018 5:58 pm

OK guys, I think I see what's happening here. I can't prove it without the Kodi database, but it appears that when I added the special 'tuner only' flag to the channel encoding I failed to initialize it when creating a channel id. It wouldn't happen in a debug build, but in a release build like what I send out you have a 50/50 chance of that being turned on accidentally.

Running through some things to see if I can get that to happen here, if so the fix is pretty easy and wouldn't require a change to the database schema (so 1.3.1 as opposed to 1.4.0)

tloughr
Posts: 49
Joined: Mon Sep 12, 2011 7:05 pm

Re: Unofficial Kodi PVR Client for HDHomeRun DVR subscribers

Post by tloughr » Sun Aug 05, 2018 7:08 pm

djp952 wrote:
Sun Aug 05, 2018 4:17 pm
tloughr wrote:
Sun Aug 05, 2018 6:34 am
djp952 wrote:
Sun Aug 05, 2018 6:08 am


Sounds like it's going directly to the tuner instead of the RECORD engine. Can you verify that you do not have "Stream Live TV channels directly from tuner device(s)" enabled, and can you check the Kodi log for a message like this:

unable to generate storage engine stream URL for channel {number} - falling back to a tuner-direct stream

The channel number encoding changed in 1.3.0, something must have gone awry that I didn't exercise. Drat! It works fine on everything here :(
Direct streaming is disabled.

7:30:29.166 T:17620 NOTICE: Creating audio stream (codec id: 86019, channels: 6, sample rate: 48000, no pass-through)
07:30:29.879 T:11108 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
07:30:30.669 T:11108 WARNING: Previous line repeats 1 times.
07:30:30.669 T:11108 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 33366
07:31:13.180 T:11108 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
07:32:25.886 T:932 WARNING: Previous line repeats 11 times.
Are you using ATSC (OTA) channels by chance? Channels with a subchannel number, like 2.1 or 10.1 as opposed to 462 or 530, typical for QAM/CableCard.
Yes, I am using Over the Air (OTA)

djp952
Posts: 808
Joined: Wed Oct 01, 2008 8:46 pm
Device ID: 131EB7F7;131ED0E0
Location: Elkridge, MD

Re: Unofficial Kodi PVR Client for HDHomeRun DVR subscribers

Post by djp952 » Sun Aug 05, 2018 7:36 pm

Problem found and fixed, building version 1.3.1 now. Just want to verify the release build(s).

>> Thank you tloughr and Ken.F <<

The change I made to the way channel identifiers were encoded was ill-advised and frankly quite stupid. I was trying to not add any more PVR database queries in an attempt to be performance-conscious, and thought I was being slick about it, but I put things back the way they were in 1.2.x and added the new query instead.

The bug with streams coming from the tuners directly when a RECORD engine is available was indeed a lack of initializing the new bit in the encoding causing randomness. The bit has been removed, so that won't be a problem anymore. You can also have channel numbers as high as 1048575 again too! LOL. (Reallocating that one bit reduced that number to 524287).

There were also a number of additional other weird little problems I caused with this stupid change; I'll refrain from the details.

Stay tuned for v1.3.1 later tonight. Maybe in an hour or so.

PS - You will probably want to "Clear Data" in Kodi PVR & Live TV Settings after installing 1.3.1. Sorry about that.

djp952
Posts: 808
Joined: Wed Oct 01, 2008 8:46 pm
Device ID: 131EB7F7;131ED0E0
Location: Elkridge, MD

Re: Unofficial Kodi PVR Client for HDHomeRun DVR subscribers

Post by djp952 » Sun Aug 05, 2018 8:35 pm

Because .0 releases never work right ...

Version 1.3.1 (2018.08.05)
  • Revert new channel identifier encoding in favor of detecting tuner-direct only streams at the time of URL generation
  • Add logging of streaming requests for 'tuner only' channels stating that the RECORD engine, if available, will not be used
Downloads:
https://github.com/djp952/pvr.hdhomerun ... /Downloads

>> REMINDER: PLEASE UPDATE THE FIRMWARE ON ALL YOUR HDHOMERUN DEVICES <<

As mentioned in the past few posts, I made a stupid mistake in 1.3.0 that messed some things up on you. The primary manifestation would be as posted by tloughr and Ken.F where streams would randomly come directly from the tuners instead of the RECORD engine so you couldn't seek them. There were actually some other more subtle issues as well. The solution was to just undo that mess and get the information I need when you're actually going to play a Live TV stream.

If upgrading from 1.3.0, you will notice a bunch of your channels and guide data will reload, this is because the internal channel identifiers were changed again. While not technically necessary you may way to "Clear Data" in the Kodi PVR & Live TV settings to ensure that no cruft from v1.3.0 is in the Kodi database. Kodi will self-correct with or without the "Clear Data" operation.

Enjoy -- I hope this will last a little while so I can get back to fixing the Leia build and figuring out how we will handle Xbox before the official Leia release!

Ken.F
Posts: 2069
Joined: Fri Apr 05, 2013 9:20 am
Device ID: 1041A706, 1043EB32, 104BAD9E, 13168DC5, 1322A7AC
Location: West Rockhill, PA

Re: Unofficial Kodi PVR Client for HDHomeRun DVR subscribers

Post by Ken.F » Mon Aug 06, 2018 7:46 am

Thanks for the update. 1.3.1 seems to be working fine on Win7 now.

djp952
Posts: 808
Joined: Wed Oct 01, 2008 8:46 pm
Device ID: 131EB7F7;131ED0E0
Location: Elkridge, MD

Re: Unofficial Kodi PVR Client for HDHomeRun DVR subscribers

Post by djp952 » Tue Aug 07, 2018 12:32 pm

Heads-up for you guys, there will need to be a 1.3.2 relatively soon. I found the main problem with playback in Leia, and it actually would affect Krypton and Jarvis too, but apparently much less frequently. Leia is being impacted moreso since it asks for a lot more data at a time from the stream.

The bug occurs when the stream buffer fills up, the HTTP library needs to be told to pause. The error on my part is how I'm resuming that paused HTTP stream. I tell it to resume, which it does, but then it immediately gets paused again because the buffer is still full. Then AFTER I tell it to resume I reset the flag that it's paused. This causes the HTTP stream to be paused indefinitely. This fix isn't going to be very hard.

Since nobody is complaining about this (yet), I'll try to wait for when Leia is ready again, but if you think you are running into this, let me know and I can throw it out there for Jarvis and Krypton first.

Thanks! So many defects lately ...

Hardax
Posts: 48
Joined: Wed Aug 09, 2017 11:01 am

Re: Unofficial Kodi PVR Client for HDHomeRun DVR subscribers

Post by Hardax » Tue Aug 07, 2018 12:53 pm

djp952 wrote:
Tue Aug 07, 2018 12:32 pm
Heads-up for you guys, there will need to be a 1.3.2 relatively soon. I found the main problem with playback in Leia, and it actually would affect Krypton and Jarvis too, but apparently much less frequently. Leia is being impacted moreso since it asks for a lot more data at a time from the stream.

The bug occurs when the stream buffer fills up, the HTTP library needs to be told to pause. The error on my part is how I'm resuming that paused HTTP stream. I tell it to resume, which it does, but then it immediately gets paused again because the buffer is still full. Then AFTER I tell it to resume I reset the flag that it's paused. This causes the HTTP stream to be paused indefinitely. This fix isn't going to be very hard.

Since nobody is complaining about this (yet), I'll try to wait for when Leia is ready again, but if you think you are running into this, let me know and I can throw it out there for Jarvis and Krypton first.

Thanks! So many defects lately ...
A Leia release sooner rather than later would be most appreciated @djp952. I just set up a new Windows client as my Nvidia Shield died and since Leia is so close to beta release I just forged ahead and went with that instead of Krypton. Looking forward to testing out the improvements!
Last edited by Hardax on Tue Aug 07, 2018 1:21 pm, edited 1 time in total.

obliojoe
Posts: 14
Joined: Fri Jul 20, 2018 1:57 pm

Re: Unofficial Kodi PVR Client for HDHomeRun DVR subscribers

Post by obliojoe » Tue Aug 07, 2018 1:09 pm

I have a consistent issue on all of my devices - these are all Nvidia Shield TV boxes running the Kodi apk installer. This has happened since the first version I tried (which I believe was 1.2.9). I just tried installing 1.3.1 and it is still happening.

The issue is that seeking causes the video to freeze when seeking backwards. It happens when watching both live and recorded videos. It does not happen when watching any other (non-PVR) videos. The freeze will happen maybe 1 out of 4 times I seek backwards - then it takes anywhere from 10 seconds to 2 minutes for the video to resume (but it seems like it always resumes eventually).

Does this sound like it may be related to the issue you mentioned above? Would it be helpful if I provide the relevant logs?

Thanks, as always, for all of your work!
obe

NYPlayer
Posts: 3538
Joined: Fri Apr 17, 2009 11:45 am
Device ID: 104112F4,104162C4,10438DAD,10438DF5,10701381,13147C7B,131A192A
Location: Portland OR, 97217

Re: Unofficial Kodi PVR Client for HDHomeRun DVR subscribers

Post by NYPlayer » Tue Aug 07, 2018 1:11 pm

obliojoe wrote:
Tue Aug 07, 2018 1:09 pm
I have a consistent issue on all of my devices - these are all Nvidia Shield TV boxes running the Kodi apk installer. This has happened since the first version I tried (which I believe was 1.2.9). I just tried installing 1.3.1 and it is still happening.

The issue is that seeking causes the video to freeze when seeking backwards. It happens when watching both live and recorded videos. It does not happen when watching any other (non-PVR) videos. The freeze will happen maybe 1 out of 4 times I seek backwards - then it takes anywhere from 10 seconds to 2 minutes for the video to resume (but it seems like it always resumes eventually).

Does this sound like it may be related to the issue you mentioned above? Would it be helpful if I provide the relevant logs?

Thanks, as always, for all of your work!
obe
I too have seen this while seeking back.

obliojoe
Posts: 14
Joined: Fri Jul 20, 2018 1:57 pm

Re: Unofficial Kodi PVR Client for HDHomeRun DVR subscribers

Post by obliojoe » Tue Aug 07, 2018 1:37 pm

Here are some logs I just grabbed ... this occurrence lasted about 40 seconds. I hope I did this right. Let me know if I can provide anything else that would help.

Code: Select all

14:12:02.892 T:1693919600   DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc
14:12:02.892 T:1693919600   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek
14:12:02.908 T:1853331824   DEBUG: CDVDAudio::Pause - pausing audio stream
14:12:02.910 T:1815595376   DEBUG: SeekTime - seek ended up on time 1031358
14:12:02.910 T:1815595376   DEBUG: demuxer seek to: 1031477.000000, success
14:12:02.910 T:1815595376   DEBUG: CVideoPlayer::FlushBuffers - flushing buffers
14:12:02.956 T:1853331824   DEBUG: CDVDAudio::Flush - flush audio stream
14:12:02.956 T:1853331824   DEBUG: CDVDAudio::Pause - pausing audio stream
14:12:02.959 T:1775176048   DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnSeek  - data: {"item":{"title":"BattleBots","type":"movie"},"player":{"playerid":-1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":0,"seconds":-30},"speed":1,"time":{"hours":0,"milliseconds":471,"minutes":17,"seconds":11}}}
14:12:02.981 T:1774139760   DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnSeek  - data: {"item":{"title":"BattleBots","type":"movie"},"player":{"playerid":-1,"seekoffset":{"hours":0,"milliseconds":0,"minutes":0,"seconds":-30},"speed":1,"time":{"hours":0,"milliseconds":471,"minutes":17,"seconds":11}}}
14:12:03.912 T:1853331824   DEBUG: CDVDMsgGeneralSynchronize - global timeout
14:12:03.913 T:1853331824   DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
14:12:03.913 T:1815595376   DEBUG: CDVDMsgGeneralSynchronize - global timeout
14:12:03.927 T:1815595376   DEBUG: CVideoPlayer::HandleMessages - player started 1
14:12:06.733 T:1692883312   DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
14:12:06.733 T:1692883312   DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------
14:12:07.874 T:1815595376   DEBUG: CVideoPlayer::SetCaching - caching state 0
14:12:07.874 T:1815595376   DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
14:12:17.311 T:1692883312    INFO: CheckIdle - Closing session to http://img.hdhomerun.com (easy=0x90ea60c0, multi=0x0)
14:12:19.364 T:1692883312    INFO: CheckIdle - Closing session to http://img.hdhomerun.com (easy=0x6ec26000, multi=0x906f25c0)
14:12:25.176 T:1809742192   DEBUG: Thread JobWorker 1809742192 terminating (autodelete)
14:12:25.176 T:1810778480   DEBUG: Thread JobWorker 1810778480 terminating (autodelete)
14:12:25.176 T:1794623856   DEBUG: Thread JobWorker 1794623856 terminating (autodelete)
14:12:25.233 T:1771030896   DEBUG: Thread JobWorker 1771030896 terminating (autodelete)
14:12:37.937 T:1852295536   DEBUG: CDVDVideoCodecAndroidMediaCodec::Reset Current state (3)
14:12:37.939 T:1852295536   DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
14:12:37.940 T:1852295536    INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
14:12:37.940 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1
14:12:37.940 T:1852295536   DEBUG: CDVDVideoCodecAndroidMediaCodec::virtual void CDVDVideoCodecAndroidMediaCodec::SetCodecControl(int) 0->4000000
14:12:37.940 T:1852295536   DEBUG: CDVDVideoCodecAndroidMediaCodec::Decode current state (2)
14:12:37.951 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.361 T:1815595376   DEBUG: Previous line repeats 36 times.
14:12:38.361 T:1815595376   DEBUG: CVideoPlayer::HandleMessages - player started 2
14:12:38.361 T:1815595376   DEBUG: VideoPlayer::Sync - Audio - pts: 1031550266.000000, cache: 260649.261470, totalcache: 464166.671038
14:12:38.361 T:1815595376   DEBUG: VideoPlayer::Sync - Video - pts: 1033451910.000000, cache: 50000.000000, totalcache: 100000.000000
14:12:38.361 T:1853331824   DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(1031289616.738530)
14:12:38.362 T:1853331824   DEBUG: CDVDAudio::Resume - resume audio stream
14:12:38.362 T:1852295536   DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(1031289616.738530)
14:12:38.362 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.362 T:1852295536   DEBUG: CDVDVideoCodecAndroidMediaCodec::virtual void CDVDVideoCodecAndroidMediaCodec::SetDropState(bool) false->true
14:12:38.362 T:1703262576   DEBUG: ActiveAE - start sync of audio stream
14:12:38.368 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.368 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.371 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.372 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.373 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.373 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.375 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.376 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.377 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.378 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.379 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.379 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.381 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.381 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.382 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.382 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.383 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.384 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.390 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.391 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.392 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.392 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.393 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.393 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.393 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.393 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.400 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.400 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.401 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.401 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.403 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.403 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.404 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.404 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.404 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.404 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.405 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.405 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.407 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.407 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.410 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.410 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.416 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.417 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.417 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.420 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.420 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.424 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.424 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.428 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.428 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.433 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.433 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.439 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.440 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.440 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.446 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.451 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.452 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.457 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.459 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.459 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.464 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.466 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.466 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.472 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.473 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.473 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.476 T:1703262576   DEBUG: ActiveAE::SyncStream - average error of 27.561835, start adjusting
14:12:38.476 T:1703262576   DEBUG: ActiveAE::SyncStream - average error 0.561835 below threshold of 30.000000
14:12:38.479 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.484 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.484 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.490 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.491 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.492 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.497 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.503 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.503 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.508 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.510 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.510 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.516 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.518 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.518 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.523 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.525 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.525 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.531 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.534 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.534 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.540 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.541 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.541 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.547 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.550 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.551 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.556 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.558 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.558 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.564 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.569 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.569 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.574 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.576 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.576 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.582 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.586 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.586 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.592 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.593 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.593 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.599 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.602 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.602 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.608 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.610 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.610 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.616 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.619 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.619 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.625 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.626 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.626 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.632 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.636 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.636 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.642 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.644 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.644 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.649 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.652 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.652 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.658 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.659 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.660 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.665 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.669 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.669 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.675 T:1852295536   DEBUG: OutputPicture - dropped in output
14:12:38.677 T:1852295536   DEBUG: Previous line repeats 1 times.
14:12:38.677 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:38.677 T:1852295536   DEBUG: CDVDVideoCodecAndroidMediaCodec::virtual void CDVDVideoCodecAndroidMediaCodec::SetDropState(bool) true->false
14:12:39.198 T:1852295536 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
14:12:41.900 T:1852295536 WARNING: Previous line repeats 1 times.
14:12:41.900 T:1852295536   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
14:12:41.908 T:1852295536   DEBUG: CDVDVideoCodecAndroidMediaCodec::virtual void CDVDVideoCodecAndroidMediaCodec::SetCodecControl(int) 4000000->0

djp952
Posts: 808
Joined: Wed Oct 01, 2008 8:46 pm
Device ID: 131EB7F7;131ED0E0
Location: Elkridge, MD

Re: Unofficial Kodi PVR Client for HDHomeRun DVR subscribers

Post by djp952 » Wed Aug 08, 2018 7:46 pm

Based on the log, this looks more like a decoding problem, but given that the bulk of the messages happen within 1-2 seconds an inability to read from the PVR could maybe be why? Poking around the interwebs these messages seem to crop up when the stream isn't keeping up with Kodi's need for data.

You guys are welcome to try a beta release for Krypton 1.3.2 to see if it makes any difference. This was just built tonight and should fix the infinite pause bug. Very light testing thus far, and only in Leia, but I don't expect any significant issues in Krypton:
https://1drv.ms/f/s!AgEGEEVzGNq-i5EUj6f3Ml7Ya2dFpA

If it's this bug I would have expected to see it start in v1.2.10 as opposed to 1.2.9 or earlier, the older ones used the more complex (overly so) original multi-threaded stream implementation. I looked back through that and don't think it would have fallen victim to the same defect, well, at least not in the same way.

Do you guys have any specific recordings that may be sharable that reproduce the problem fairly often? Short of that, any specific channels? It's possible I have the same ones available here.

I would have guessed based on the symptoms that it was a bug fixed in 1.2.10 - "Fix bug causing misalignment of MPEG-TS packets on seeks satisfied by data in the ring buffer", but clearly that can't be it if it's still happening. That bug could/would/will have caused problems on seeks if the seek was small and was able to be satisfied by the existing buffer. It may still be a bug in that area, of course, if I messed it up once chances are decent that I messed it up more than once :mrgreen:

Locked