Page 1 of 1

Errors getting guide updates the last several days

Posted: Sat Apr 27, 2019 1:07 am
by gsr
Hi Gary,

First off, thanks for all the effort you've put into epg123.

Last night, I noticed I had the red error in the main page in WMC, so I took a look at the log file and discovered a number of errors. I just confirmed that my username and password are correct, as some of the errors suggest my login might be bad. Here are some snippets from the log, including the version number of epg123:

Code: Select all

[4/20/2019 2:00:00 AM] ===============================================================================
[4/20/2019 2:00:00 AM]  Beginning epg123 update execution. version 1.2.12.0
[4/20/2019 2:00:01 AM] ===============================================================================


[4/23/2019 2:00:21 AM] [ INFO] Successfully retrieved Md5s for 238 station's daily schedules. (0:00:00:14.7608442 /   433.098 KB)
[4/23/2019 2:00:26 AM] [ INFO] Successfully retrieved 219 station's daily schedules.          (0:00:00:03.7842165 /     4.355 MB)
[4/23/2019 2:00:39 AM] [ INFO] Successfully retrieved Md5s for 238 station's daily schedules. (0:00:00:09.0595182 /   431.378 KB)
[4/23/2019 2:00:43 AM] [ INFO] Successfully retrieved 213 station's daily schedules.          (0:00:00:02.7111551 /     5.136 MB)
[4/23/2019 2:00:48 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/23/2019 2:00:48 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-23T06:00:50Z
[4/23/2019 2:00:49 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/23/2019 2:00:49 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-23T06:00:50Z
[4/23/2019 2:00:49 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/23/2019 2:00:49 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-23T06:00:51Z
[4/23/2019 2:00:49 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/23/2019 2:00:49 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-23T06:00:51Z
[4/23/2019 2:00:49 AM] [ERROR] Failed to complete request. Exiting
[4/23/2019 2:00:49 AM] [ERROR] Did not receive a response from Schedules Direct for Md5s of 238 station's daily schedules. (0:00:00:00.8820505)
[4/23/2019 2:00:49 AM] [ERROR] Problem occurred during getMd5ScheduleEntries(). Exiting.
[4/23/2019 2:00:49 AM] [ERROR] Failed to create MXF file. Exiting.
[4/23/2019 2:00:49 AM] [ INFO] epg123 update execution time was 00:00:48.3297643.
[4/23/2019 2:00:49 AM] [WARNG] It appears the MXF file to import is incorrect. Changing the import file from "c:\epg123\epg123.mxf" to "c:\epg123\output\epg123.mxf".


[4/24/2019 2:05:25 AM] [ INFO] Found 51660 cached program descriptions.
[4/24/2019 2:05:26 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/24/2019 2:05:26 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-24T06:05:26Z
[4/24/2019 2:05:26 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/24/2019 2:05:26 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-24T06:05:26Z
[4/24/2019 2:05:26 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/24/2019 2:05:26 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-24T06:05:26Z
[4/24/2019 2:05:26 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/24/2019 2:05:26 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-24T06:05:26Z
[4/24/2019 2:05:26 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/24/2019 2:05:26 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-24T06:05:26Z
[4/24/2019 2:05:26 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/24/2019 2:05:26 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-24T06:05:26Z
[4/24/2019 2:05:26 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/24/2019 2:05:26 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-24T06:05:26Z
[4/24/2019 2:05:26 AM] [ERROR] Failed to complete request. Exiting
[4/24/2019 2:05:26 AM] [ERROR] Did not receive a response from Schedules Direct for 1099 program descriptions. (0:00:00:00.5240300)
[4/24/2019 2:05:26 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/24/2019 2:05:26 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-24T06:05:26Z
[4/24/2019 2:05:26 AM] [ERROR] Failed to complete request. Exiting
[4/24/2019 2:05:26 AM] [ERROR] Did not receive a response from Schedules Direct for 5000 program descriptions. (0:00:00:00.6330362)
[4/24/2019 2:05:26 AM] [WARNG] Problem occurred during buildAllProgramEntries(). Did not process all program description responses.
[4/24/2019 2:05:26 AM] [ INFO] Processed 51660 program descriptions.
[4/24/2019 2:05:26 AM] Exiting buildAllProgramEntries(). SUCCESS.
[4/24/2019 2:05:26 AM] Entering buildAllGenericSeriesInfoDescriptions() for 11492 series.
[4/24/2019 2:05:55 AM] [ INFO] Found 11492 cached series descriptions.
[4/24/2019 2:05:55 AM] [ INFO] Processed 11492 series descriptions.
[4/24/2019 2:05:55 AM] Exiting buildAllGenericSeriesInfoDescriptions(). SUCCESS.
[4/24/2019 2:05:55 AM] Entering getAllMoviePosters() for 5492 movies.
[4/24/2019 2:05:56 AM] [ INFO] Successfully retrieved artwork info for 500 programs. (0:00:00:00.7070404 /     4.193 MB)
[4/24/2019 2:05:56 AM] [ INFO] Successfully retrieved artwork info for 500 programs. (0:00:00:00.7510429 /     4.157 MB)


[4/24/2019 2:11:00 AM] [ INFO] Completed lineup refresh.
[4/24/2019 2:11:00 AM] Entering runWmcTask(ReindexSearchRoot)
[4/24/2019 2:11:00 AM] [ INFO] SUCCESS: Attempted to run the scheduled task "Microsoft\Windows\Media Center\ReindexSearchRoot".
[4/24/2019 2:11:00 AM] [ INFO] Successfully started the ReindexSearchRoot task. Exit code: 0
[4/24/2019 2:11:00 AM] Exiting runWmcTask(ReindexSearchRoot). SUCCESS.
[4/24/2019 2:11:00 AM] Entering runWmcTask(PvrScheduleTask)
[4/24/2019 2:11:00 AM] [ INFO] SUCCESS: Attempted to run the scheduled task "Microsoft\Windows\Media Center\PvrScheduleTask".
[4/24/2019 2:11:00 AM] [ INFO] Successfully started the PvrScheduleTask task. Exit code: 0
[4/24/2019 2:11:00 AM] Exiting runWmcTask(PvrScheduleTask). SUCCESS.
[4/24/2019 2:11:00 AM] [ERROR] The imported MXF file contained an ERROR in its status field.
[4/24/2019 2:11:00 AM] [ INFO] Completed EPG123 client execution.
[4/24/2019 2:11:00 AM] [ INFO] EPG123 client execution time was 00:04:36.3018035.


[4/25/2019 2:05:21 AM] [ INFO] Found 48240 cached program descriptions.
[4/25/2019 2:05:21 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/25/2019 2:05:21 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-25T06:05:22Z
[4/25/2019 2:05:21 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/25/2019 2:05:21 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-25T06:05:22Z
[4/25/2019 2:05:21 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/25/2019 2:05:21 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-25T06:05:22Z
[4/25/2019 2:05:21 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/25/2019 2:05:21 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-25T06:05:22Z
[4/25/2019 2:05:21 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/25/2019 2:05:21 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-25T06:05:22Z
[4/25/2019 2:05:21 AM] [ERROR] Failed to complete request. Exiting
[4/25/2019 2:05:21 AM] [ERROR] Did not receive a response from Schedules Direct for 4651 program descriptions. (0:00:00:00.7190411)
[4/25/2019 2:05:21 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/25/2019 2:05:21 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-25T06:05:22Z
[4/25/2019 2:05:22 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/25/2019 2:05:22 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-25T06:05:22Z
[4/25/2019 2:05:22 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/25/2019 2:05:22 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-25T06:05:23Z
[4/25/2019 2:05:22 AM] [ERROR] Failed to complete request. Exiting
[4/25/2019 2:05:22 AM] [ERROR] Did not receive a response from Schedules Direct for 5000 program descriptions. (0:00:00:01.1270645)
[4/25/2019 2:05:22 AM] [WARNG] Problem occurred during buildAllProgramEntries(). Did not process all program description responses.
[4/25/2019 2:05:22 AM] [ INFO] Processed 48240 program descriptions.
[4/25/2019 2:05:22 AM] Exiting buildAllProgramEntries(). SUCCESS.
[4/25/2019 2:05:22 AM] Entering buildAllGenericSeriesInfoDescriptions() for 11127 series.
[4/25/2019 2:05:50 AM] [ INFO] Found 11127 cached series descriptions.
[4/25/2019 2:05:50 AM] [ INFO] Processed 11127 series descriptions.


[4/25/2019 2:10:23 AM] [ INFO] Successfully started the ReindexSearchRoot task. Exit code: 0
[4/25/2019 2:10:23 AM] Exiting runWmcTask(ReindexSearchRoot). SUCCESS.
[4/25/2019 2:10:23 AM] Entering runWmcTask(PvrScheduleTask)
[4/25/2019 2:10:23 AM] [ INFO] SUCCESS: Attempted to run the scheduled task "Microsoft\Windows\Media Center\PvrScheduleTask".
[4/25/2019 2:10:23 AM] [ INFO] Successfully started the PvrScheduleTask task. Exit code: 0
[4/25/2019 2:10:23 AM] Exiting runWmcTask(PvrScheduleTask). SUCCESS.
[4/25/2019 2:10:23 AM] [ERROR] The imported MXF file contained an ERROR in its status field.
[4/25/2019 2:10:23 AM] [ INFO] Completed EPG123 client execution.
[4/25/2019 2:10:23 AM] [ INFO] EPG123 client execution time was 00:04:04.9720116.


[4/26/2019 2:06:13 AM] Exiting buildAllProgramEntries(). SUCCESS.
[4/26/2019 2:06:13 AM] Entering buildAllGenericSeriesInfoDescriptions() for 12158 series.
[4/26/2019 2:06:41 AM] [ INFO] Found 10896 cached series descriptions.
[4/26/2019 2:06:41 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/26/2019 2:06:41 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-26T06:06:43Z
[4/26/2019 2:06:41 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/26/2019 2:06:41 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/26/2019 2:06:41 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-26T06:06:43Z
[4/26/2019 2:06:42 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/26/2019 2:06:42 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-26T06:06:43Z
[4/26/2019 2:06:42 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/26/2019 2:06:42 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-26T06:06:43Z
[4/26/2019 2:06:42 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/26/2019 2:06:42 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-26T06:06:43Z
[4/26/2019 2:06:42 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/26/2019 2:06:42 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-26T06:06:43Z
[4/26/2019 2:06:42 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/26/2019 2:06:42 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-26T06:06:43Z
[4/26/2019 2:06:42 AM] [ERROR] Failed to complete request. Exiting
[4/26/2019 2:06:42 AM] [ERROR] Did not receive a response from Schedules Direct for 262 generic program descriptions. (0:00:00:00.3940226)
[4/26/2019 2:06:42 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError
[4/26/2019 2:06:42 AM] [ INFO] SD responded with error code: 4003 , message: Invalid username or token has expired. , serverID: 20141201.web.1 , datetime: 2019-04-26T06:06:43Z
[4/26/2019 2:06:42 AM] [ INFO] SD API WebException Thrown. Message: The remote server returned an error: (403) Forbidden. , Status: ProtocolError


[4/26/2019 2:15:32 AM] [ INFO] Completed the automatch of lineup stations to tuner channels.
[4/26/2019 2:15:32 AM] [ INFO] Completed lineup refresh.
[4/26/2019 2:15:32 AM] Entering runWmcTask(ReindexSearchRoot)
[4/26/2019 2:15:32 AM] [ INFO] SUCCESS: Attempted to run the scheduled task "Microsoft\Windows\Media Center\ReindexSearchRoot".
[4/26/2019 2:15:32 AM] [ INFO] Successfully started the ReindexSearchRoot task. Exit code: 0
[4/26/2019 2:15:32 AM] Exiting runWmcTask(ReindexSearchRoot). SUCCESS.
[4/26/2019 2:15:32 AM] Entering runWmcTask(PvrScheduleTask)
[4/26/2019 2:15:32 AM] [ INFO] SUCCESS: Attempted to run the scheduled task "Microsoft\Windows\Media Center\PvrScheduleTask".
[4/26/2019 2:15:32 AM] [ INFO] Successfully started the PvrScheduleTask task. Exit code: 0
[4/26/2019 2:15:32 AM] Exiting runWmcTask(PvrScheduleTask). SUCCESS.
[4/26/2019 2:15:32 AM] [ERROR] The imported MXF file contained an ERROR in its status field.
[4/26/2019 2:15:32 AM] [ INFO] Completed EPG123 client execution.
[4/26/2019 2:15:32 AM] [ INFO] EPG123 client execution time was 00:05:08.5876502.
Any suggestions? If you need the entire log file, I can provide that.

Re: Errors getting guide updates the last several days

Posted: Sat Apr 27, 2019 1:48 am
by garyan2
It looks to me you may have another program/machine doing an update as well. You can only have 1 active token at a time, so when another machine or process requests a token, the one in use is being expired.

Do you have another machine/process getting guide listings from SD at around the same time?

Re: Errors getting guide updates the last several days

Posted: Sat Apr 27, 2019 2:19 am
by gsr
Yeah, JRiver Media Center is using the same service. They’ve been coexisting for as long as I’ve been using EPG123 though, which is well over a year. I’ll have to check what time the guide update is running on that system and see if I can adjust things so they don’t overlap.

Thanks for the response.

Re: Errors getting guide updates the last several days

Posted: Sat Apr 27, 2019 5:50 am
by garyan2
You might want to look at letting EPG123 create an XMLTV file to be imported into JRiver. I've never used it myself, but it looks like it would support that.

Re: Errors getting guide updates the last several days

Posted: Sat Apr 27, 2019 11:07 pm
by gsr
garyan2 wrote: Sat Apr 27, 2019 5:50 am You might want to look at letting EPG123 create an XMLTV file to be imported into JRiver. I've never used it myself, but it looks like it would support that.
I've considered that, but one of the main reasons I'm using both WMC and JRiver is for redundancy and keeping them completely separate is preferable for that purpose. I've had more than a few cases where one or the other (more often WMC) has failed to record something either due to bad guide data or because it lost connectivity to the tuners, but the other picked it up.

Last night after your initial response, I adjusted JRiver to update at 4AM and that appears to have resolved the main issue. I just checked my logs from last night and the only thing that looks a bit odd is this:

Code: Select all

[4/27/2019 2:07:53 AM] [WARNG] It appears the MXF file to import is incorrect. Changing the import file from "c:\epg123\epg123.mxf" to "c:\epg123\output\epg123.mxf".
"c:\epg123\epg123.mxf" has a date of 2/4/2019 and "c:\epg123\output\epg123.mxf" has a date of 4/27/2019

There were no other warnings or errors last night.

Re: Errors getting guide updates the last several days

Posted: Sat Apr 27, 2019 11:24 pm
by garyan2
gsr wrote: Sat Apr 27, 2019 11:07 pm

Code: Select all

[4/27/2019 2:07:53 AM] [WARNG] It appears the MXF file to import is incorrect. Changing the import file from "c:\epg123\epg123.mxf" to "c:\epg123\output\epg123.mxf".
"c:\epg123\epg123.mxf" has a date of 2/4/2019 and "c:\epg123\output\epg123.mxf" has a date of 4/27/2019

There were no other warnings or errors last night.
When I did some folder structure changes earlier this year, the message to delete and recreate your scheduled task was getting lost and a lot of folks were no longer getting real-time updates in their guide. I added code to detect for this condition and adapt, but will still log that the task is incorrect.

To clear the message, just delete your current task and create a new one (note your options to automatically import and automatically match). Alternatively, you can edit the task directly in Task Scheduler to change the path to the mxf file.

Re: Errors getting guide updates the last several days

Posted: Sat Apr 27, 2019 11:42 pm
by gsr
garyan2 wrote: Sat Apr 27, 2019 11:24 pm When I did some folder structure changes earlier this year, the message to delete and recreate your scheduled task was getting lost and a lot of folks were no longer getting real-time updates in their guide. I added code to detect for this condition and adapt, but will still log that the task is incorrect.

To clear the message, just delete your current task and create a new one (note your options to automatically import and automatically match). Alternatively, you can edit the task directly in Task Scheduler to change the path to the mxf file.
That makes sense - I was obviously one of the people who forgot to pay attention. :) All fixed now, thanks!