[mattbrown]

Hello,
I've built a HTPC using Kodi 15.2 and MythTv as the backend. I have FiOS, and use the Ceton InfiniTV 6 with cablecard and a Haupauge 1250 tuner card for OTA. Channel listing comes down from schedule direct and all is working great. PVR is outstanding. When watching Live TV, I run into an occasional problem. While navigating through the channels in Kodi's EPG, occasionally the screen will freeze for about 5 seconds, and then jump back to the top to Channel 2. HDTV starts at channel 500, so then I have to scroll down to get back to where I was. It seems to be random when it does it.

Matt

[nickr]

Debug log (wiki)

[mattbrown]

OK, I finally got around to enabling debug log, and it looks lik the EPG is running an update and that coincided with the EPG reset. This log snippet coincides with the EPG resetting to channel 2.

Code:

.
.
.
16:21:37 T:140258515605248   DEBUG: Previous line repeats 254 times.
16:21:37 T:140258515605248   DEBUG: CDVDPlayer::CheckContinuity - wrapback :1, prev:17425417877.777779, curr:17425417400.000000, diff:-477.777779
16:21:38 T:140258532390656   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
16:21:44 T:140258507212544   DEBUG: Previous line repeats 29 times.
16:21:44 T:140258507212544   DEBUG: CDVDClock::Discontinuity - CDVDPlayerAudio::HandleSyncError2 - was:17427189372.913422, should be:17427157769.813953, error:-31603.099468
16:21:44 T:140258532390656   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
16:21:58 T:140258641430272   DEBUG: Previous line repeats 70 times.
16:21:58 T:140258641430272   DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'WTTG-DT' from client '771'
16:21:58 T:140258641430272   DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'BUZZR  ' from client '771'
16:21:58 T:140258641430272   DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'WDCW-DT' from client '771'
16:21:58 T:140258641430272   DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'AntTV  ' from client '771'
16:21:58 T:140258641430272   DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'ThisTV ' from client '771'
16:21:58 T:140258641430272   DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'qubo' from client '771'
16:21:58 T:140258641430272   DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'IONLife' from client '771'
16:21:58 T:140258641430272   DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'Shop' from client '771'
16:21:58 T:140258641430272   DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'HSN' from client '771'
.
{Similar lines removed for space}
.
16:22:00 T:140258641430272   DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'Music Choice: Blues' from client '771'
16:22:00 T:140258641430272   DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'Music Choice: Singers & Swing' from client '771'
16:22:00 T:140258641430272   DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'Music Choice: Easy Listening' from client '771'
16:22:00 T:140258641430272   DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'Music Choice: Classical Masterpieces' from client '771'
16:22:00 T:140258641430272   DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'Music Choice: Light Classical' from client '771'
16:22:00 T:140259419105024   DEBUG: CPVRTimers - Update - updating timers
16:22:00 T:140259419105024   DEBUG: AddOnLog: MythTV PVR Client: GetUpcomingRecordings: Skipping How the Universe Works:End of the Universe on Science Channel HD because status 2 and m_showNotRecording=0
16:22:00 T:140259419105024   DEBUG: PVRTimers - UpdateEntries - updated timer 1445849 on client 771
16:22:00 T:140259419105024   DEBUG: PVRTimers - UpdateEntries - updated timer 460825 on client 771
16:22:00 T:140259419105024   DEBUG: PVRTimers - UpdateEntries - updated timer 1708265 on client 771
16:22:00 T:140259419105024   DEBUG: PVRTimers - UpdateEntries - updated timer 736715 on client 771
16:22:00 T:140259419105024   DEBUG: PVRTimers - UpdateEntries - updated timer 838283 on client 771
16:22:00 T:140259419105024   DEBUG: PVRTimers - UpdateEntries - updated timer 1103260 on client 771
16:22:00 T:140259419105024   DEBUG: PVRTimers - UpdateEntries - updated timer 1102172 on client 771
.
{Similar lines removed for space}
.
16:22:00 T:140259419105024   DEBUG: PVRTimers - UpdateEntries - updated timer 1090397 on client 771
16:22:00 T:140259419105024   DEBUG: PVRTimers - UpdateEntries - updated timer 750414 on client 771
16:22:00 T:140259419105024   DEBUG: PVRTimers - UpdateEntries - updated timer 828382 on client 771
16:22:00 T:140259419105024   DEBUG: PVRTimers - UpdateEntries - updated timer 730254 on client 771
16:22:00 T:140259419105024   DEBUG: PVRTimers - UpdateEntries - updated timer 847950 on client 771
16:22:00 T:140259419105024   DEBUG: PVRTimers - UpdateEntries - updated timer 723678 on client 771
16:22:00 T:140259419105024   DEBUG: PVRTimers - UpdateEntries - updated timer 787855 on client 771
16:22:00 T:140258532390656 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
16:22:00 T:140260285532096   DEBUG: CGUIEPGGridContainer - SetStartEnd - start=11/21/2015 9:00:00 PM end=11/25/2015 9:00:00 PM
16:22:00 T:140258532390656 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
16:22:02 T:140260285532096 WARNING: Previous line repeats 1 times.
16:22:02 T:140260285532096   DEBUG: CGUIEPGGridContainer - UpdateItems completed successfully in 126 ms
16:22:02 T:140258532390656   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
16:22:02 T:140258532390656   DEBUG: Previous line repeats 3 times.
16:22:02 T:140258532390656   DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -0.951287, Bufferlevel: 1, Gain: 0.033373
16:22:02 T:140258532390656   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
16:22:02 T:140258532390656   DEBUG: Previous line repeats 1 times.
16:22:02 T:140258532390656   DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -0.951287, Bufferlevel: 2, Gain: 0.033361
16:22:02 T:140258532390656   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
16:22:02 T:140258532390656   DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -0.864853, Bufferlevel: 1, Gain: 0.033373
16:22:02 T:140258532390656   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1

[metaron]

If you can post a link to the full log, that would be better.
There is lots of config information at the start of the file which will probably help.

[mattbrown]

Here's the entire log, the file is 415K so posting it's content is not feasible. I only noticed the EPG jump at 16:22. You'll see from the log that it does the UpdateFromScraper several times during the run, but I only happened to be in the EPG at 16:22 when it happened. I also now believe that when it jumps to the top of the guide, if I just let it sit there for some period of time (I don't know how much time) it WILL go back to the channel it was on. I was running back and forth between the TV and the PC to check the log. I left the EPG up when it jumped to CH2. When I returned to Kodi a few minutes later, the EPG was back on channel 510 (Fox45 Baltimore) and I don't think I put it there. Next time it happens I will wait it out and see if it does jump back on its own.

Kodi Debug log

[metaron]

Thanks for the log @mattbrown.

I've taken a quick look and from the symptoms you describe the behaviour sounds like something the code might do:
1) Start an update of the EPG
2) Invalidate the channel currently selected as the update for this channel has started, so default the selected channel to some default (i.e. probably the 1st entry in the list (surprised it's not channel 1 rather than channel 2 that gets selected)
3) Complete the update (you have rather a lot of channels - 778 channels according to the log!)
4) Process all the new entries (not sure how many days of EPG data you store, the default is 3 but you might have increased it)
5) Work out something isn't right and put your selected channel back where it was.

I had a quick look in the code but couldn't find anything obvious and it's getting late.

You could try setting some of your 'not used' channels to invisible in the backend or reducing the number of EPG days shown in the guide and then resetting the EPG database.
Then try to reproduce the symptoms to see if this reduces the time it takes for your selected channel to 'correct itself' after it flips to channel 2.
Let me know if doing this changes the symptoms as it might help narrow down which bit of the code to look in for a culprit.

(it sounds like a kodi core thing not a pvr.mythtv thing by the way)

[mattbrown]

I made hundreds of channels not viewable in the MythWeb backend, and set the update guide to 2 days worth. Its still happening, but I have not turned logging back on so no new data. It also does not seem to be returning back to the original channel, or I'm not giving it enough time (a few minutes). Oh and Channel 2 is the first entry in the Guide. I'm using the Channel numbers from the Myth Backend so I don't have a Channel 1. Also it seems to do it more after a reset. After Kodi has been on a while a day or two, the problem seems to be minimal. Could it be related to scanning over the 7 tuners I have activated (6 on the Ceton for FiOs and 1 on the Haupauge OTA)?