Query Failure on Timed Update

An evolving, supported alternative to Rovi
Forum rules
★ Download the latest EPG123 here: https://garyan2.github.io/ <> Setup guide here: https://garyan2.github.io/install.html
Post Reply
Alan G

Posts: 89
Joined: Mon Feb 22, 2016 8:27 pm
Location: Bethesda, Maryland

HTPC Specs: Show details

Query Failure on Timed Update

#1

Post by Alan G » Wed Jul 26, 2017 12:21 pm

For the past couple days, I am getting a failure noti[*]ce for the 3AM update (bad gateway and I've posted the logfile. I posted earlier under the bug report about a glitch getting popup warning windows (viewtopic.php?p=120621#p120621 ). I got a series of windows last night, "This Provider is Attempting to Launch Windows Media Center; Windows Media Center Extension; Do you want to allow this Provider" There were four or five of the windows and I checked this morning after the failed update and there were again a couple of these windows. I did change the task schedule for 'mcupdate' to run only at startup (I don't know whether this update command is necessary for EPG123 so I need some advice on this). I can run a manual update without any issues at all. Even on the days that EPG123 worked normal it seemed that the guide was not imported into WMC.

I'm happy to do some trouble shooting but would like to now what the best approach would be.

Code: Select all

[7/25/2017 3:00:00 AM] ===============================================================================
[7/25/2017 3:00:00 AM]  Beginning epg123 update execution.
[7/25/2017 3:00:00 AM] ===============================================================================
[7/25/2017 3:00:01 AM] [ INFO] Beginning EPG123 update execution. 2017-07-25 07:00:01Z
[7/25/2017 3:00:01 AM] [ INFO] DaysToDownload: 14 , PrefixEpisodeTitle: True , AppendEpisodeDesc: True , TMDbCoverArt: True , LogoOverride: True , AutoAddNew: False
[7/25/2017 3:00:10 AM] [ INFO] Token request successful. serverID: 20141201.web.1
[7/25/2017 3:00:19 AM] [ INFO] Status request successful. account expires: 2018-07-18T22:55:42Z , lineups: 1/4 , lastDataUpdate: 2017-07-25T03:27:22Z
[7/25/2017 3:00:19 AM] [ INFO] system status: Online , message: No known issues.
[7/25/2017 3:00:28 AM] [ INFO] epg123 is up to date. version: 1.1.10
[7/25/2017 3:00:28 AM] [ INFO] Successfully retrieved TMDb configurations.
[7/25/2017 3:00:36 AM] [ INFO] Successfully requested listing of client lineups from Schedules Direct.
[7/25/2017 3:00:36 AM] Entering buildLineupServices() for 1 lineups.
[7/25/2017 3:00:46 AM] [ INFO] Successfully retrieved the station mapping for lineup USA-MD67788-X.
[7/25/2017 3:00:47 AM] Exiting buildLineupServices(). SUCCESS.
[7/25/2017 3:00:47 AM] Entering getAllScheduleEntryMd5s() for 14 days on 654 stations.
[7/25/2017 3:01:30 AM] [ INFO] Successfully retrieved Md5s for 654 station's daily schedules.
[7/25/2017 3:01:33 AM] [ INFO] Found 6854 cached daily schedules.
[7/25/2017 3:03:28 AM] [ INFO] Successfully retrieved 653 station's daily schedules.
[7/25/2017 3:03:43 AM] [ INFO] Downloaded 2299 daily schedules.
[7/25/2017 3:04:22 AM] [ INFO] Processed 9153 daily schedules for 654 stations.
[7/25/2017 3:04:22 AM] Exiting getAllScheduleEntryMd5s(). SUCCESS.
[7/25/2017 3:04:22 AM] Entering buildAllProgramEntries() for 46383 programs.
[7/25/2017 3:07:18 AM] [ INFO] Found 43061 cached program descriptions.
[7/25/2017 3:07:26 AM] [ INFO] Successfully retrieved 3322 program descriptions.
[7/25/2017 3:07:41 AM] [ INFO] Processed 46383 program descriptions.
[7/25/2017 3:07:41 AM] Exiting buildAllProgramEntries(). SUCCESS.
[7/25/2017 3:07:41 AM] Entering buildAllGenericSeriesInfoDescriptions() for 10719 series.
[7/25/2017 3:08:12 AM] [ INFO] Found 10419 cached series descriptions.
[7/25/2017 3:08:13 AM] [ INFO] Successfully retrieved 300 generic program descriptions.
[7/25/2017 3:08:14 AM] [ INFO] Processed 10719 series descriptions.
[7/25/2017 3:08:14 AM] Exiting buildAllGenericSeriesInfoDescriptions(). SUCCESS.
[7/25/2017 3:08:14 AM] Entering getAllMoviePosters() for 5158 movies.
[7/25/2017 3:08:14 AM] [ INFO] Found 4805 cached movie poster links.
[7/25/2017 3:08:15 AM] [ INFO] Successfully retrieved artwork info for 353 programs.
[7/25/2017 3:08:59 AM] Entering getAllSeriesImages() for 10719 series.
[7/25/2017 3:08:59 AM] [ INFO] Found 9646 cached series image links.
[7/25/2017 3:09:00 AM] [ INFO] Successfully retrieved artwork info for 500 programs.
[7/25/2017 3:09:02 AM] [ INFO] Successfully retrieved artwork info for 500 programs.
[7/25/2017 3:09:03 AM] [ INFO] Successfully retrieved artwork info for 73 programs.
[7/25/2017 3:09:03 AM] [ INFO] Processed 10719 series image links.
[7/25/2017 3:09:03 AM] Exiting getAllSeriesImages(). SUCCESS.
[7/25/2017 3:09:03 AM] [ INFO] Completed compiling keywords and keyword groups.
[7/25/2017 3:09:03 AM] Entering writeMxf().
[7/25/2017 3:09:04 AM] [ INFO] Completed save of mxf file.
[7/25/2017 3:09:04 AM] Exiting writeMxf(). SUCCESS.
[7/25/2017 3:09:04 AM] Entering writeImageArchive().
[7/25/2017 3:09:04 AM] [ INFO] Completed save of image archive file.
[7/25/2017 3:09:05 AM] Exiting writeImageArchive(). SUCCESS.
[7/25/2017 3:09:12 AM] [ INFO] 5897 files deleted from the cache directory during cleanup.
[7/25/2017 3:09:12 AM] [ INFO] Generated .mxf file contains 654 services, 10719 series, 46383 programs, and 66852 people with 15962 image links.
[7/25/2017 3:09:12 AM] [ INFO] Completed EPG123 update execution. SUCCESS.
[7/25/2017 3:09:12 AM] [ INFO] epg123 update execution time was 00:09:11.3907719.
[7/25/2017 3:09:13 AM] ===============================================================================
[7/25/2017 3:09:13 AM]  Beginning epg123 client execution.
[7/25/2017 3:09:13 AM] ===============================================================================
[7/25/2017 3:09:13 AM] [ INFO] Beginning epg123 client execution. 2017-07-25 07:09:13Z
[7/25/2017 3:09:13 AM] [ INFO] Import: True , Match: True , NoLogo: False , Force: False
[7/25/2017 3:09:13 AM] [ INFO] epg123 client version: 1.1.10 for Win7
[7/25/2017 3:09:14 AM] Entering importMxfFile() for file "C:\epg123\epg123.mxf"
[7/25/2017 3:11:45 AM] [ INFO] Successfully imported .mxf file into Media Center database. Exit code: 0
[7/25/2017 3:11:45 AM] Exiting importMxfFile(). SUCCESS.
[7/25/2017 3:11:47 AM] [ INFO] Completed the automatch of lineup stations to tuner channels.
[7/25/2017 3:11:47 AM] Entering reindexDatabase()
[7/25/2017 3:11:47 AM] [ INFO] SUCCESS: Attempted to run the scheduled task "Microsoft\Windows\Media Center\ReindexSearchRoot".
[7/25/2017 3:11:47 AM] [ INFO] Successfully started the ReindexSearchRoot task. Exit code: 0
[7/25/2017 3:11:47 AM] Exiting reindexDatabase(). SUCCESS.
[7/25/2017 3:11:47 AM] [ INFO] Completed EPG123 client execution.
[7/25/2017 3:11:47 AM] [ INFO] EPG123 client execution time was 00:02:34.1702159.
[7/25/2017 7:02:53 AM] ===============================================================================
[7/25/2017 7:02:53 AM]  Activating the epg123 configuration GUI.
[7/25/2017 7:02:53 AM] ===============================================================================
[7/25/2017 7:02:54 AM] [ INFO] epg123 is up to date. version: 1.1.10
[7/25/2017 7:02:55 AM] [ INFO] Successfully queried the Task Scheduler for status. Ready. Last Run 7/25/2017 3:00:00 AM; Exit: 0x00000000
[7/25/2017 7:02:55 AM] [ INFO] Token request successful. serverID: 20141201.web.1
[7/25/2017 7:02:55 AM] [ INFO] Status request successful. account expires: 2018-07-18T22:55:42Z , lineups: 1/4 , lastDataUpdate: 2017-07-25T03:27:22Z
[7/25/2017 7:02:55 AM] [ INFO] system status: Online , message: No known issues.
[7/25/2017 7:02:55 AM] [ INFO] Successfully requested listing of client lineups from Schedules Direct.
[7/25/2017 7:02:55 AM] [ INFO] Successfully retrieved the station mapping for lineup USA-MD67788-X.
[7/25/2017 6:49:00 PM] ===============================================================================
[7/25/2017 6:49:01 PM]  Activating the epg123 configuration GUI.
[7/25/2017 6:49:01 PM] ===============================================================================
[7/25/2017 6:49:01 PM] [ INFO] epg123 is up to date. version: 1.1.10
[7/25/2017 6:49:02 PM] [ INFO] Successfully queried the Task Scheduler for status. Ready. Last Run 7/25/2017 3:00:00 AM; Exit: 0x00000000
[7/25/2017 6:49:02 PM] [ INFO] Token request successful. serverID: 20141201.web.1
[7/25/2017 6:49:02 PM] [ INFO] Status request successful. account expires: 2018-07-18T22:55:42Z , lineups: 1/4 , lastDataUpdate: 2017-07-25T14:47:53Z
[7/25/2017 6:49:02 PM] [ INFO] system status: Online , message: No known issues.
[7/25/2017 6:49:02 PM] [ INFO] Successfully requested listing of client lineups from Schedules Direct.
[7/25/2017 6:49:02 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-MD67788-X.
[7/26/2017 3:00:00 AM] ===============================================================================
[7/26/2017 3:00:00 AM]  Beginning epg123 update execution.
[7/26/2017 3:00:00 AM] ===============================================================================
[7/26/2017 3:00:00 AM] [ INFO] Beginning EPG123 update execution. 2017-07-26 07:00:00Z
[7/26/2017 3:00:00 AM] [ INFO] DaysToDownload: 14 , PrefixEpisodeTitle: True , AppendEpisodeDesc: True , TMDbCoverArt: False , LogoOverride: True , AutoAddNew: False
[7/26/2017 3:00:01 AM] [ERROR] SD API WebException Thrown. Message: The remote server returned an error: (502) Bad Gateway. , Status: ProtocolError
[7/26/2017 3:00:01 AM] [ERROR] Did not receive a response from Schedules Direct for a token request.
[7/26/2017 3:00:01 AM] [ERROR] Failed to retrieve token from Schedules Direct. message: Did not receive a response from Schedules Direct for a token request.
[7/26/2017 3:00:01 AM] [ INFO] epg123 update execution time was 00:00:00.4600006.
[7/26/2017 3:00:01 AM] ===============================================================================
[7/26/2017 3:00:01 AM]  Beginning epg123 client execution.
[7/26/2017 3:00:01 AM] ===============================================================================
[7/26/2017 3:00:01 AM] [ INFO] Beginning epg123 client execution. 2017-07-26 07:00:01Z
[7/26/2017 3:00:01 AM] [ INFO] Import: True , Match: True , NoLogo: False , Force: False
[7/26/2017 3:00:01 AM] [ INFO] epg123 client version: 1.1.10 for Win7
[7/26/2017 3:00:01 AM] Entering importMxfFile() for file "C:\epg123\epg123.mxf"
[7/26/2017 3:02:12 AM] [ INFO] Successfully imported .mxf file into Media Center database. Exit code: 0
[7/26/2017 3:02:12 AM] Exiting importMxfFile(). SUCCESS.
[7/26/2017 3:02:14 AM] [ INFO] Completed the automatch of lineup stations to tuner channels.
[7/26/2017 3:02:14 AM] Entering reindexDatabase()
[7/26/2017 3:02:14 AM] [ INFO] SUCCESS: Attempted to run the scheduled task "Microsoft\Windows\Media Center\ReindexSearchRoot".
[7/26/2017 3:02:14 AM] [ INFO] Successfully started the ReindexSearchRoot task. Exit code: 0
[7/26/2017 3:02:14 AM] Exiting reindexDatabase(). SUCCESS.
[7/26/2017 3:02:14 AM] [ INFO] Completed EPG123 client execution.
[7/26/2017 3:02:14 AM] [ INFO] EPG123 client execution time was 00:02:12.8401860.

User avatar
garyan2

Posts: 7438
Joined: Fri Nov 27, 2015 7:23 pm
Location:

HTPC Specs: Show details

#2

Post by garyan2 » Thu Jul 27, 2017 3:03 am

Sorry, been a loooooooong day.

Not sure what is causing the popups, but epg123 does not try to launch WMC. All epg123 does is import the guide listings using loadmxf.exe which based on the log file is working properly. I could guess that when the computer wakes up to update the guide at 3AM, mcupdate starts as well and does its thing with the database.

I typically suggest leaving the mcupdate tasks alone. They still serve a function even though they don't download any guide information from Rovi. The MC in mcupdate means Media Center which is more than just TV and the listings.

I would be concerned about the appearance that the guide isn't being updated when everything seems to run okay. What are your indications?
- Gary
Keeping WMC alive beyond January 2020. https://garyan2.github.io

Alan G

Posts: 89
Joined: Mon Feb 22, 2016 8:27 pm
Location: Bethesda, Maryland

HTPC Specs: Show details

#3

Post by Alan G » Thu Jul 27, 2017 11:35 am

garyan2 wrote:Sorry, been a loooooooong day.

Not sure what is causing the popups, but epg123 does not try to launch WMC. All epg123 does is import the guide listings using loadmxf.exe which based on the log file is working properly. I could guess that when the computer wakes up to update the guide at 3AM, mcupdate starts as well and does its thing with the database.

I typically suggest leaving the mcupdate tasks alone. They still serve a function even though they don't download any guide information from Rovi. The MC in mcupdate means Media Center which is more than just TV and the listings.

I would be concerned about the appearance that the guide isn't being updated when everything seems to run okay. What are your indications?
Thanks. I don't know why I get the Media Extender popup warning as I've never installed one. I checked the WMC settings and they confirm that there is no extender loaded. I didn't get a popup warning yesterday so I will keep monitoring that. I can always check the box not to show the warning again as it may just be some kind of glitch that isn't doing anything.

I'll recheck the mcupdate tasks and make sure they are set accordingly.

I reset the update task yesterday by changing the time from 3 am to 4am. Just checked the trace log and it looks like it did a normal update overnight. Perhaps the problem over the last couple of days was a simple gateway query problem and when EPG123 gets a bad signal it does not do a re-query to attempt the download (at least I think this is how you have programmed it). I've never had any issued with doing the manual update.

I forgot to note that I'm running EPG123 on a Win7 machine and it's installed in its own root directory (not the program files) with a Hauppage dual channel tuner (2650) which I don't think needed any special setup (the HD PVR button was greyed out).

User avatar
garyan2

Posts: 7438
Joined: Fri Nov 27, 2015 7:23 pm
Location:

HTPC Specs: Show details

#4

Post by garyan2 » Fri Jul 28, 2017 1:07 am

Alan G wrote:Perhaps the problem over the last couple of days was a simple gateway query problem and when EPG123 gets a bad signal it does not do a re-query to attempt the download (at least I think this is how you have programmed it). I've never had any issued with doing the manual update.
Yah, I will retry on a timeout but if I get any other error I kick out and abort the update. If you don't notice the error, it will certainly try again on the next scheduled update time.
- Gary
Keeping WMC alive beyond January 2020. https://garyan2.github.io

Post Reply