NextPVR Forums
  • ______
  • Home
  • New Posts
  • Wiki
  • Members
  • Help
  • Search
  • Register
  • Login
  • Home
  • Wiki
  • Members
  • Help
  • Search
NextPVR Forums Public NextPVR Support Legacy (v4.x and earlier) v
« Previous 1 … 151 152 153 154 155 … 433 Next »
Not sure if EPG is updating on new 3.6.6 install on Windows 10.

Not sure if EPG is updating on new 3.6.6 install on Windows 10.
ReplayHarry
Offline

Member
Posts: 87
Threads: 12
Joined: Feb 2014
#1
2015-12-14, 06:42 PM
Just put in 3.6.6 this morning on a just upgraded Windows 10 HTPC. It seems to be working fine but...

I did a manual EPG update (thru settings) and all seemed to go well, but then it seemed to hang there for a very long time on "Saving EPG".

Could you take a quick look at my logs and see if indeed the EPG got updated ok? Using schedules Direct.

Thanks,
johnsonx42
Offline

Posting Freak
Posts: 7,298
Threads: 189
Joined: Sep 2008
#2
2015-12-15, 03:57 AM
looks like an EPG update to me; you got some database lock errors while it was saving the epg data, which explains the long delay, but they don't seem to have caused any problem. might have been contention between npvr and tvlistings, I'm really not sure.

@sub-

why is all the raw Schedules Direct data being dumped into the logs? I'm not seeing any of that data being dumped into my logs.

and why does it do this ScheduledRecordings.Load() sequence so many times? 2 or 3 times for every single scheduling event:
Code:
2015-12-14 10:17:13.269    [DEBUG][18]    ScheduledRecordings.Load()
2015-12-14 10:17:13.269    [DEBUG][18]    - removeMissingRecordings
2015-12-14 10:17:13.270    [DEBUG][18]    - checkForRenamedRecordings
2015-12-14 10:17:13.270    [DEBUG][18]    ScheduledRecordings.Load()@exit
server: NextPVR 5.0.7/Win10 2004/64-bit/AMD A6-7400k/hvr-2250 & hvr-1250/Winegard Flatwave antenna/Schedules Direct
main client: NextPVR 5.0.7 Desktop Client; LG 50UH5500 WebOS 3.0 TV
sub
Offline

Administrator

NextPVR HQ, New Zealand
Posts: 106,810
Threads: 769
Joined: Nov 2003
#3
2015-12-15, 04:25 AM
johnsonx42 Wrote:@sub-

why is all the raw Schedules Direct data being dumped into the logs? I'm not seeing any of that data being dumped into my logs.
Which log are you seeing this? I can't see where you mean.

Quote:and why does it do this ScheduledRecordings.Load() sequence so many times? 2 or 3 times for every single scheduling event:
Code:
2015-12-14 10:17:13.269    [DEBUG][18]    ScheduledRecordings.Load()
2015-12-14 10:17:13.269    [DEBUG][18]    - removeMissingRecordings
2015-12-14 10:17:13.270    [DEBUG][18]    - checkForRenamedRecordings
2015-12-14 10:17:13.270    [DEBUG][18]    ScheduledRecordings.Load()@exit
For each season recording it processes, it load the list of completed recordings and pending recordings, before looking for possible matches. In theory it could be optimized down to once for each per EPG update, but these lower level functions currently handle everything they need to do update a recurring recording, in isolation. Currently the overnight processing of the EPG effectively just calls the lower level function to process each recurring recording. It's always done this - it's only obvious now because I added some logging when I was looking into someones issue. I should remove these extra messages to get rid of some of the noise...
johnsonx42
Offline

Posting Freak
Posts: 7,298
Threads: 189
Joined: Sep 2008
#4
2015-12-15, 05:19 AM
sub Wrote:Which log are you seeing this? I can't see where you mean.
look at replayharry's npvr.log.2 and npvr.log.1, almost the entire log file is filled with schedules direct scheduling data, and that's two log files filled and rolled over for a single EPG update.

Quote:For each season recording it processes, it load the list of completed recordings and pending recordings, before looking for possible matches. In theory it could be optimized down to once for each per EPG update, but these lower level functions currently handle everything they need to do update a recurring recording, in isolation. Currently the overnight processing of the EPG effectively just calls the lower level function to process each recurring recording. It's always done this - it's only obvious now because I added some logging when I was looking into someones issue. I should remove these extra messages to get rid of some of the noise...
It's doing it at least twice every time it tries to schedule a single episode, not just once per season recording. I do understand it's often easier and less prone to bugs to just let the code loop through redundant steps (particularly when they take only 1ms), since you never know when you may call the same code in a way that those redundant steps are suddenly needed. I'm just looking at it from a from a support standpoint, verbose logs are great until they become too verbose.
server: NextPVR 5.0.7/Win10 2004/64-bit/AMD A6-7400k/hvr-2250 & hvr-1250/Winegard Flatwave antenna/Schedules Direct
main client: NextPVR 5.0.7 Desktop Client; LG 50UH5500 WebOS 3.0 TV
sub
Offline

Administrator

NextPVR HQ, New Zealand
Posts: 106,810
Threads: 769
Joined: Nov 2003
#5
2015-12-15, 06:58 AM
johnsonx42 Wrote:look at replayharry's npvr.log.2 and npvr.log.1, almost the entire log file is filled with schedules direct scheduling data, and that's two log files filled and rolled over for a single EPG update.
Ah, I see what you mean. It's being logged because their is error messages being returned along with the valid data. ie, a they have a pile of channels not associated with any of the lineups on their account, so these channels wont download listings. The app logs this info to give us clues when we get users saying "i've got some channels with no listings..."

Quote:{"code":7030,"message":"You have requested a schedule which is not in any of your configured lineups.","modified":"1970-01-01T00:00:00Z","md5":"CAFEDEADBEEFCAFEDEADBE","startDate":"2015-12-14"}},{"stationID":"14909","serverID":"20141201.web.2","code":7030,"response":"SCHEDULE_NOT_IN_LINEUP","programs":[],"metadata":{"code":7030,"message":"You have requested a schedule which is not in any of your configured lineups.","modified":"1970-01-01T00:00:00Z","md5":"CAFEDEADBEEFCAFEDEADBE","startDate":"2015-12-15"}},{"stationID":"14909","serverID":"20141201.web.2","code":7030,"response":"SCHEDULE_NOT_IN_LINEUP","programs":[],"metadata":{"code":7030,"message":"You have requested a schedule which is not in any of your configured lineups.","modified":"1970-01-01T00:00:00Z","md5":"CAFEDEADBEEFCAFEDEADBE","startDate":"2015-12-16"}},
...etc...

Quote:It's doing it at least twice every time it tries to schedule a single episode, not just once per season recording.
I just checked this here, and I'm only seeing the expected two calls per recurring recording.
johnsonx42
Offline

Posting Freak
Posts: 7,298
Threads: 189
Joined: Sep 2008
#6
2015-12-15, 07:48 AM
Here's an example from ReplayHarry's npvr.log.1; two ScheduledRecordings.Load() calls for the recurring recording, and then two more calls (after ScheduleRecording@2.2 and @2.3) for each episode being scheduled, that's a ton of logging for one program:
Code:
2015-12-14 10:16:49.604    [INFO][18]    UpdateRecurringRecording(103, 'Jeopardy!'):
<Rules><EPGTitle>Jeopardy!</EPGTitle><ChannelOID>7663</ChannelOID><ChannelName>KXTV-DT</ChannelName><StartTime>2014-02-26T03:00:00.0000000Z</StartTime><EndTime>2014-02-26T03:30:00.0000000Z</EndTime><PrePadding>1</PrePadding><PostPadding>2</PostPadding><Quality>0</Quality><Keep>5</Keep><RecordingDirectoryID>[Jan's Stuff]</RecordingDirectoryID><OnlyNewEpisodes>true</OnlyNewEpisodes></Rules>
2015-12-14 10:16:49.605    [DEBUG][18]    ScheduledRecordings.Load()
2015-12-14 10:16:49.605    [DEBUG][18]    - removeMissingRecordings
2015-12-14 10:16:49.605    [DEBUG][18]    - checkForRenamedRecordings
2015-12-14 10:16:49.611    [DEBUG][18]    ScheduledRecordings.Load()@exit
2015-12-14 10:16:49.612    [DEBUG][18]    ScheduledRecordings.Load()
2015-12-14 10:16:49.612    [DEBUG][18]    - removeMissingRecordings
2015-12-14 10:16:49.612    [DEBUG][18]    - checkForRenamedRecordings
2015-12-14 10:16:49.623    [DEBUG][18]    ScheduledRecordings.Load()@exit
2015-12-14 10:16:49.872    [DEBUG][18]    LoadListingsMatchingTitle(channel=7663,title=Jeopardy!)
2015-12-14 10:16:49.879    [DEBUG][18]     - Potential episode at: 12/14/2015 7:00:00 PM
2015-12-14 10:16:49.879    [DEBUG][18]       ...check IsValid()
2015-12-14 10:16:49.879    [DEBUG][18]       ...scheduling episode
2015-12-14 10:16:49.879    [DEBUG][18]    ScheduleRecording@1
2015-12-14 10:16:49.879    [DEBUG][18]    ScheduleRecording@2.0
2015-12-14 10:16:49.879    [DEBUG][18]    ScheduleRecording@2.1
2015-12-14 10:16:49.879    [DEBUG][18]    CaptureSource.LoadAll()
2015-12-14 10:16:49.883    [DEBUG][18]    ScheduleRecording@2.2
2015-12-14 10:16:49.884    [DEBUG][18]    ScheduleRecording@2.2
2015-12-14 10:16:49.885    [DEBUG][18]    ScheduledRecordings.Load()
2015-12-14 10:16:49.885    [DEBUG][18]    - removeMissingRecordings
2015-12-14 10:16:49.885    [DEBUG][18]    - checkForRenamedRecordings
2015-12-14 10:16:49.887    [DEBUG][18]    ScheduledRecordings.Load()@exit
2015-12-14 10:16:49.887    [DEBUG][18]    ScheduleRecording@2.3
2015-12-14 10:16:49.888    [DEBUG][18]    ScheduledRecordings.Load()
2015-12-14 10:16:49.888    [DEBUG][18]    - removeMissingRecordings
2015-12-14 10:16:49.888    [DEBUG][18]    - checkForRenamedRecordings
2015-12-14 10:16:49.890    [DEBUG][18]    ScheduledRecordings.Load()@exit
2015-12-14 10:16:49.890    [DEBUG][18]    ScheduleRecording@2.4
2015-12-14 10:16:49.890    [DEBUG][18]    ScheduleRecording@3
2015-12-14 10:16:49.910    [DEBUG][29]    TV Listings: RecordingSchedule: database file change detected, increasing schedule update timer interval.
2015-12-14 10:16:49.917    [DEBUG][18]    ScheduleRecording@4
2015-12-14 10:16:49.917    [DEBUG][18]    ScheduleRecording@5
2015-12-14 10:16:49.917    [DEBUG][18]     - Potential episode at: 12/15/2015 7:00:00 PM
2015-12-14 10:16:49.917    [DEBUG][18]       ...check IsValid()
2015-12-14 10:16:49.917    [DEBUG][18]       ...scheduling episode
2015-12-14 10:16:49.917    [DEBUG][18]    ScheduleRecording@1
2015-12-14 10:16:49.917    [DEBUG][18]    ScheduleRecording@2.0
2015-12-14 10:16:49.917    [DEBUG][18]    ScheduleRecording@2.1
2015-12-14 10:16:49.917    [DEBUG][18]    CaptureSource.LoadAll()
2015-12-14 10:16:49.925    [DEBUG][18]    ScheduleRecording@2.2
2015-12-14 10:16:49.926    [DEBUG][18]    ScheduleRecording@2.2
2015-12-14 10:16:49.927    [DEBUG][18]    ScheduledRecordings.Load()
2015-12-14 10:16:49.927    [DEBUG][18]    - removeMissingRecordings
2015-12-14 10:16:49.927    [DEBUG][18]    - checkForRenamedRecordings
2015-12-14 10:16:49.928    [DEBUG][18]    ScheduledRecordings.Load()@exit
2015-12-14 10:16:49.929    [DEBUG][18]    ScheduleRecording@2.3
2015-12-14 10:16:49.930    [DEBUG][18]    ScheduledRecordings.Load()
2015-12-14 10:16:49.930    [DEBUG][18]    - removeMissingRecordings
2015-12-14 10:16:49.930    [DEBUG][18]    - checkForRenamedRecordings
2015-12-14 10:16:49.931    [DEBUG][18]    ScheduledRecordings.Load()@exit
2015-12-14 10:16:49.932    [DEBUG][18]    ScheduleRecording@2.4
2015-12-14 10:16:49.932    [DEBUG][18]    ScheduleRecording@3
2015-12-14 10:16:49.949    [DEBUG][29]    TV Listings: RecordingSchedule: database file change detected, increasing schedule update timer interval.
2015-12-14 10:16:49.955    [DEBUG][18]    ScheduleRecording@4
2015-12-14 10:16:49.955    [DEBUG][18]    ScheduleRecording@5
2015-12-14 10:16:49.955    [DEBUG][18]     - Potential episode at: 12/16/2015 7:00:00 PM
2015-12-14 10:16:49.956    [DEBUG][18]       ...check IsValid()
2015-12-14 10:16:49.956    [DEBUG][18]       ...scheduling episode
2015-12-14 10:16:49.956    [DEBUG][18]    ScheduleRecording@1
2015-12-14 10:16:49.956    [DEBUG][18]    ScheduleRecording@2.0
2015-12-14 10:16:49.956    [DEBUG][18]    ScheduleRecording@2.1
2015-12-14 10:16:49.956    [DEBUG][18]    CaptureSource.LoadAll()
2015-12-14 10:16:49.963    [DEBUG][18]    ScheduleRecording@2.2
2015-12-14 10:16:49.964    [DEBUG][18]    ScheduleRecording@2.2
2015-12-14 10:16:49.965    [DEBUG][18]    ScheduledRecordings.Load()
2015-12-14 10:16:49.965    [DEBUG][18]    - removeMissingRecordings
2015-12-14 10:16:49.965    [DEBUG][18]    - checkForRenamedRecordings
2015-12-14 10:16:49.967    [DEBUG][18]    ScheduledRecordings.Load()@exit
2015-12-14 10:16:49.968    [DEBUG][18]    Checking if multi-record possible...
2015-12-14 10:16:49.971    [DEBUG][18]    ScheduleRecording@2.2
2015-12-14 10:16:49.971    [DEBUG][18]    ScheduledRecordings.Load()
2015-12-14 10:16:49.971    [DEBUG][18]    - removeMissingRecordings
2015-12-14 10:16:49.971    [DEBUG][18]    - checkForRenamedRecordings
2015-12-14 10:16:49.972    [DEBUG][18]    ScheduledRecordings.Load()@exit
2015-12-14 10:16:49.972    [DEBUG][18]    ScheduleRecording@2.3
2015-12-14 10:16:49.973    [DEBUG][18]    ScheduledRecordings.Load()
2015-12-14 10:16:49.973    [DEBUG][18]    - removeMissingRecordings
2015-12-14 10:16:49.973    [DEBUG][18]    - checkForRenamedRecordings
2015-12-14 10:16:49.975    [DEBUG][18]    ScheduledRecordings.Load()@exit
2015-12-14 10:16:49.975    [DEBUG][18]    ScheduleRecording@2.4
2015-12-14 10:16:49.975    [DEBUG][18]    ScheduleRecording@3
2015-12-14 10:16:49.998    [DEBUG][29]    TV Listings: RecordingSchedule: database file change detected, increasing schedule update timer interval.
2015-12-14 10:16:50.005    [DEBUG][18]    ScheduleRecording@4
2015-12-14 10:16:50.005    [DEBUG][18]    ScheduleRecording@5
2015-12-14 10:16:50.005    [DEBUG][18]     - Potential episode at: 12/17/2015 7:00:00 PM
2015-12-14 10:16:50.005    [DEBUG][18]       ...check IsValid()
2015-12-14 10:16:50.005    [DEBUG][18]       ...scheduling episode
2015-12-14 10:16:50.005    [DEBUG][18]    ScheduleRecording@1
2015-12-14 10:16:50.005    [DEBUG][18]    ScheduleRecording@2.0
2015-12-14 10:16:50.005    [DEBUG][18]    ScheduleRecording@2.1
2015-12-14 10:16:50.005    [DEBUG][18]    CaptureSource.LoadAll()
2015-12-14 10:16:50.013    [DEBUG][18]    ScheduleRecording@2.2
2015-12-14 10:16:50.014    [DEBUG][18]    ScheduleRecording@2.2
2015-12-14 10:16:50.015    [DEBUG][18]    ScheduledRecordings.Load()
2015-12-14 10:16:50.015    [DEBUG][18]    - removeMissingRecordings
2015-12-14 10:16:50.015    [DEBUG][18]    - checkForRenamedRecordings
2015-12-14 10:16:50.016    [DEBUG][18]    ScheduledRecordings.Load()@exit
2015-12-14 10:16:50.016    [DEBUG][18]    ScheduleRecording@2.3
2015-12-14 10:16:50.018    [DEBUG][18]    ScheduledRecordings.Load()
2015-12-14 10:16:50.018    [DEBUG][18]    - removeMissingRecordings
2015-12-14 10:16:50.018    [DEBUG][18]    - checkForRenamedRecordings
2015-12-14 10:16:50.020    [DEBUG][18]    ScheduledRecordings.Load()@exit
2015-12-14 10:16:50.020    [DEBUG][18]    ScheduleRecording@2.4
2015-12-14 10:16:50.020    [DEBUG][18]    ScheduleRecording@3
2015-12-14 10:16:50.040    [DEBUG][29]    TV Listings: RecordingSchedule: database file change detected, increasing schedule update timer interval.
2015-12-14 10:16:50.046    [DEBUG][18]    ScheduleRecording@4
2015-12-14 10:16:50.046    [DEBUG][18]    ScheduleRecording@5
2015-12-14 10:16:50.046    [DEBUG][18]     - Potential episode at: 12/18/2015 7:00:00 PM
2015-12-14 10:16:50.046    [DEBUG][18]       ...check IsValid()
2015-12-14 10:16:50.046    [DEBUG][18]       ...scheduling episode
2015-12-14 10:16:50.046    [DEBUG][18]    ScheduleRecording@1
2015-12-14 10:16:50.046    [DEBUG][18]    ScheduleRecording@2.0
2015-12-14 10:16:50.046    [DEBUG][18]    ScheduleRecording@2.1
2015-12-14 10:16:50.046    [DEBUG][18]    CaptureSource.LoadAll()
2015-12-14 10:16:50.054    [DEBUG][18]    ScheduleRecording@2.2
2015-12-14 10:16:50.055    [DEBUG][18]    ScheduleRecording@2.2
2015-12-14 10:16:50.056    [DEBUG][18]    ScheduledRecordings.Load()
2015-12-14 10:16:50.056    [DEBUG][18]    - removeMissingRecordings
2015-12-14 10:16:50.056    [DEBUG][18]    - checkForRenamedRecordings
2015-12-14 10:16:50.058    [DEBUG][18]    ScheduledRecordings.Load()@exit
2015-12-14 10:16:50.058    [DEBUG][18]    ScheduleRecording@2.3
2015-12-14 10:16:50.059    [DEBUG][18]    ScheduledRecordings.Load()
2015-12-14 10:16:50.059    [DEBUG][18]    - removeMissingRecordings
2015-12-14 10:16:50.059    [DEBUG][18]    - checkForRenamedRecordings
2015-12-14 10:16:50.061    [DEBUG][18]    ScheduledRecordings.Load()@exit
2015-12-14 10:16:50.061    [DEBUG][18]    ScheduleRecording@2.4
2015-12-14 10:16:50.061    [DEBUG][18]    ScheduleRecording@3
2015-12-14 10:16:50.085    [DEBUG][29]    TV Listings: RecordingSchedule: database file change detected, increasing schedule update timer interval.
2015-12-14 10:16:50.091    [DEBUG][18]    ScheduleRecording@4
2015-12-14 10:16:50.091    [DEBUG][18]    ScheduleRecording@5
....
(truncated to fit in one post, was ~24000 characters long)
server: NextPVR 5.0.7/Win10 2004/64-bit/AMD A6-7400k/hvr-2250 & hvr-1250/Winegard Flatwave antenna/Schedules Direct
main client: NextPVR 5.0.7 Desktop Client; LG 50UH5500 WebOS 3.0 TV
ReplayHarry
Offline

Member
Posts: 87
Threads: 12
Joined: Feb 2014
#7
2015-12-15, 05:09 PM
Glad you guys know what's going on. I'm just happy my EPG updates are working correctly although with maybe more logging then necessary. My EPG is done just after 3am when nothing else is going on. Only reason I noticed the "long delay issue" was because I forced an update. So all is well with me and Windows 10 along with NPVR 3.6.6.

By the way, nice feature added that makes it easy to get the logs zipped and ready to send.
« Next Oldest | Next Newest »

Users browsing this thread: 1 Guest(s)



Possibly Related Threads…
Thread Author Replies Views Last Post
  Lights-Out for Windows Home Server 2011? TuckerDean 1 1,411 2021-01-28, 01:52 PM
Last Post: mvallevand
  Screensaver on Windows 10 NumberFive 0 1,135 2020-12-24, 10:21 AM
Last Post: NumberFive
  Flashing in on-screen graphics (Windows 10) (Can post logs if needed) bgtees 39 13,533 2020-08-19, 12:38 PM
Last Post: Stanno
  NextPVR Recording Service doesn't install from Services SamM 4 2,156 2020-08-02, 01:11 AM
Last Post: SamM
  Lights-Out for Windows Home Server 2011 pvrip 6 3,366 2020-05-21, 04:56 PM
Last Post: Madlec
  EPG not updating (DVB ATSC EPG) Flyndr 3 3,014 2020-04-29, 10:42 AM
Last Post: Graham
  Windows 10 Record Issues Captain Jack 11 5,686 2020-02-02, 09:11 PM
Last Post: Captain Jack
  New Install - No Notification Icon LeoL 3 1,603 2020-02-01, 04:41 PM
Last Post: sub
  MCE Remote Opens NextPVR Behind Other Windows 1sae 3 2,462 2020-01-13, 06:19 PM
Last Post: Stanno
  TV signal breaking up in Windows 10 wg22 8 4,226 2020-01-06, 08:49 AM
Last Post: wg22

  • View a Printable Version
  • Subscribe to this thread
Forum Jump:

© Designed by D&D, modified by NextPVR - Powered by MyBB

Linear Mode
Threaded Mode