v1.7.0.2 Release

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
cbarbie

Posts: 25
Joined: Thu Mar 10, 2016 11:42 am
Location:

HTPC Specs: Show details

#21

Post by cbarbie » Thu Sep 16, 2021 6:05 pm

I updated this afternoon and ran the update 3 times. Each time the XML load errors "Unhandled Exception: System.ObjectDisposedException: Cannot access a disposed object."
Everthing ran fine with 1.6.7.0. Run log for all 4 shown below. Rebooted 3 times.

Win7 PC running WMC

Code: Select all

[9/16/2021 4:30:00 AM] ===============================================================================
[9/16/2021 4:30:00 AM]  Beginning epg123 update execution. version 1.6.7.0
[9/16/2021 4:30:00 AM] ===============================================================================
[9/16/2021 4:30:00 AM] [ INFO] Beginning EPG123 update execution. 2021-09-16 08:30:00Z
[9/16/2021 4:30:00 AM] [ INFO] DaysToDownload: 14 , TheTVDBNumbers : False , PrefixEpisodeTitle: True , PrefixEpisodeDescription : True , AppendEpisodeDesc: True , OADOverride : True , TMDbCoverArt: True , IncludeSDLogos : False , AutoAddNew: True , CreateXmltv: False , ModernMediaUiPlusSupport: False
[9/16/2021 4:30:01 AM] [ INFO] Token request successful. serverID: 20141201.web.1 , datetime: 2021-09-16T08:29:59Z
[9/16/2021 4:30:01 AM] [ INFO] Status request successful. account expires: 2022-02-11T18:22:35Z , lineups: 2/4 , lastDataUpdate: 2021-09-15T22:14:00Z
[9/16/2021 4:30:01 AM] [ INFO] system status: Online , message: No known issues.
[9/16/2021 4:30:03 AM] [ INFO] GetClientVersion() Unknown exception thrown. Message: The operation has timed out
[9/16/2021 4:30:04 AM] [ INFO] Successfully retrieved TMDb configurations.
[9/16/2021 4:30:04 AM] [ INFO] Successfully requested listing of subscribed lineups from Schedules Direct.
[9/16/2021 4:30:04 AM] Entering BuildLineupServices() for 2 lineups.
[9/16/2021 4:30:04 AM] [ INFO] Successfully retrieved the station mapping for lineup USA-OTA-23233.
[9/16/2021 4:30:05 AM] [ INFO] Successfully retrieved the station mapping for lineup USA-VA45521-X.
[9/16/2021 4:30:05 AM] Exiting BuildLineupServices(). SUCCESS.
[9/16/2021 4:30:05 AM] Entering GetAllScheduleEntryMd5s() for 14 days on 380 stations.
[9/16/2021 4:30:11 AM] [ INFO] Successfully retrieved Md5s for 357 station's daily schedules. (0:00:00:05.9300083 /   532.240 KB)
[9/16/2021 4:30:18 AM] [ INFO] Successfully retrieved 357 station's daily schedules.          (0:00:00:06.7200094 /     4.712 MB)
[9/16/2021 4:30:21 AM] [ INFO] Successfully retrieved Md5s for  23 station's daily schedules. (0:00:00:00.3000004 /    34.305 KB)
[9/16/2021 4:30:21 AM] [ INFO] Successfully retrieved  23 station's daily schedules.          (0:00:00:00.2500003 /   218.222 KB)
[9/16/2021 4:30:22 AM] [ INFO] Found 4436 cached daily schedules.
[9/16/2021 4:30:22 AM] [ INFO] Downloaded 884 daily schedules.
[9/16/2021 4:30:24 AM] [ INFO] Processed 5320 daily schedules for 380 stations.
[9/16/2021 4:30:24 AM] Exiting GetAllScheduleEntryMd5s(). SUCCESS.
[9/16/2021 4:30:24 AM] Entering BuildAllProgramEntries() for 36115 programs.
[9/16/2021 4:30:29 AM] [ INFO] Found 33927 cached program descriptions.
[9/16/2021 4:30:38 AM] [ INFO] Successfully retrieved 2188 program descriptions. (0:00:00:09.0000126 /     4.193 MB)
[9/16/2021 4:30:40 AM] [ INFO] Processed 36115 program descriptions.
[9/16/2021 4:30:40 AM] Exiting BuildAllProgramEntries(). SUCCESS.
[9/16/2021 4:30:40 AM] Entering BuildAllGenericSeriesInfoDescriptions() for 4733 series.
[9/16/2021 4:30:40 AM] [ INFO] Found 4691 cached/unavailable series descriptions.
[9/16/2021 4:30:40 AM] [ INFO] Successfully retrieved  42 generic program descriptions. (0:00:00:00.3100004 /    11.886 KB)
[9/16/2021 4:30:40 AM] [ INFO] Problem occurred during BuildGenericSeriesInfoDescriptions(). Did not process all series descriptions.
[9/16/2021 4:30:40 AM] [ INFO] Processed 4732 series descriptions.
[9/16/2021 4:30:40 AM] Exiting BuildAllGenericSeriesInfoDescriptions(). SUCCESS.
[9/16/2021 4:30:40 AM] Entering GetAllMoviePosters() for 2777 movies.
[9/16/2021 4:30:40 AM] [ INFO] Found 2694 cached/unavailable movie poster links.
[9/16/2021 4:30:41 AM] [ INFO] Successfully retrieved artwork info for  83 programs. (0:00:00:00.4800006 /     1.011 MB)
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "The Death of Dick Long" from 2019 found 1 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "Scary Movie 5" from 2013 found 2 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "A Hijacking" from 2012 found 1 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "Murdered" from 2012 found 4 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "My Neighbour" from 2012 found 1 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "Sacrifice" from 2020 found 9 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "The Freshman" from 1925 found 1 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "City Lights" from 1931 found 1 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "Flesh and the Devil" from 1926 found 1 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "Hear My Song" from 1991 found 1 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "Promises in the Dark" from 1979 found 1 results.
[9/16/2021 4:30:42 AM] [ INFO] TMDb catalog search for "Network" from 1976 found 1 results.
[9/16/2021 4:30:42 AM] [ INFO] Processed 2777 movie poster links.
[9/16/2021 4:30:42 AM] Exiting GetAllMoviePosters(). SUCCESS.
[9/16/2021 4:30:42 AM] Entering GetAllSeriesImages() for 4733 series.
[9/16/2021 4:30:42 AM] [ INFO] Found 4488 cached/unavailable series image links.
[9/16/2021 4:30:42 AM] [ INFO] Successfully retrieved artwork info for 245 programs. (0:00:00:00.7000010 /   904.887 KB)
[9/16/2021 4:30:43 AM] [ INFO] Processed 4733 series image links.
[9/16/2021 4:30:43 AM] Exiting GetAllSeriesImages(). SUCCESS.
[9/16/2021 4:30:43 AM] Entering GetAllSeasonImages() for 4479 seasons.
[9/16/2021 4:30:43 AM] [ INFO] Found 4380 cached/unavailable season image links.
[9/16/2021 4:30:43 AM] [ INFO] Successfully retrieved artwork info for  99 programs. (0:00:00:00.4400007 /   768.244 KB)
[9/16/2021 4:30:43 AM] [ INFO] Processed 4479 season image links.
[9/16/2021 4:30:43 AM] Exiting GetAllSeasonImages(). SUCCESS.
[9/16/2021 4:30:43 AM] Entering GetAllSportsImages() for 0 sports events.
[9/16/2021 4:30:43 AM] [ INFO] Found 0 cached/unavailable sport event image links.
[9/16/2021 4:30:43 AM] [ INFO] Processed 0 sport event image links.
[9/16/2021 4:30:43 AM] Exiting GetAllSportsImages(). SUCCESS.
[9/16/2021 4:30:43 AM] [ INFO] Completed compiling keywords and keyword groups.
[9/16/2021 4:30:46 AM] [ INFO] Completed save of the MXF file to "C:\epg123\output\epg123.mxf".
[9/16/2021 4:30:46 AM] [ INFO] 3667 entries deleted from the cache file during cleanup.
[9/16/2021 4:30:47 AM] [ INFO] Downloaded and processed    12.328 MB of data from Schedules Direct.
[9/16/2021 4:30:47 AM] [ INFO] Generated .mxf file contains 380 services, 4733 series, 4479 seasons, 36115 programs, 132059 schedule entries, and 56470 people with 8684 image links.
[9/16/2021 4:30:47 AM] [ INFO] Completed EPG123 update execution. SUCCESS.
[9/16/2021 4:30:47 AM] [ INFO] epg123 update execution time was 00:00:47.4500665.
[9/16/2021 4:30:48 AM] ===============================================================================
[9/16/2021 4:30:48 AM]  Beginning epg123 client execution. version 1.6.7.0
[9/16/2021 4:30:48 AM] ===============================================================================
[9/16/2021 4:30:48 AM] [ INFO] Beginning epg123 client execution. 2021-09-16 08:30:48Z
[9/16/2021 4:30:48 AM] [ INFO] Import: True , Match: True , NoLogo: False , Force: False , ShowProgress: False , NoGC: False , NoVerify: False , Verbose: False
[9/16/2021 4:30:52 AM] Entering ImportMxfFile() for file "c:\epg123\output\epg123.mxf".
[9/16/2021 4:34:10 AM] [ INFO] Successfully imported .mxf file into Media Center database. Exit code: 0
[9/16/2021 4:34:10 AM] Exiting ImportMxfFile(). SUCCESS.
[9/16/2021 4:34:10 AM] Entering VerifyLoad()
[9/16/2021 4:34:11 AM] [ INFO] Service WRICDT has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:15 AM] [ INFO] Service WRIC has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:18 AM] [ INFO] Service OWN has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:20 AM] [ INFO] Service MTV has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:21 AM] [ INFO] Service WE has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:24 AM] [ INFO] Service BBCWDNA has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:28 AM] [ INFO] Service CBSSN has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:30 AM] [ INFO] Service WEHD has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:32 AM] [ INFO] Service CBSSNHD has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:34 AM] [ INFO] Service MTVHD has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:35 AM] [ INFO] Service OWNHD has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:38 AM] [ INFO] Checked 127635 entries and corrected 84 of them.
[9/16/2021 4:34:38 AM] Exiting VerifyLoad()
[9/16/2021 4:34:40 AM] [ INFO] Completed the automatch of lineup stations to tuner channels.
[9/16/2021 4:34:41 AM] [ INFO] Completed lineup refresh.
[9/16/2021 4:34:41 AM] Entering RunWmcIndexTask(PvrScheduleTask)
[9/16/2021 4:34:41 AM] [ INFO] SUCCESS: Attempted to run the scheduled task "Microsoft\Windows\Media Center\PvrScheduleTask".
[9/16/2021 4:34:41 AM] [ INFO] Successfully started the PvrScheduleTask task. Exit code: 0
[9/16/2021 4:34:41 AM] Exiting RunWmcIndexTask(PvrScheduleTask). SUCCESS.
[9/16/2021 4:34:41 AM] Entering RunWmcIndexTask(ReindexSearchRoot)
[9/16/2021 4:34:42 AM] [ INFO] SUCCESS: Attempted to run the scheduled task "Microsoft\Windows\Media Center\ReindexSearchRoot".
[9/16/2021 4:34:42 AM] [ INFO] Successfully started the ReindexSearchRoot task. Exit code: 0
[9/16/2021 4:34:42 AM] Exiting RunWmcIndexTask(ReindexSearchRoot). SUCCESS.
[9/16/2021 4:34:42 AM] [ INFO] MXF file was created on 9/16/2021 4:30:00 AM
[9/16/2021 4:34:42 AM] [ INFO] Completed EPG123 client execution.
[9/16/2021 4:34:48 AM] [ INFO] EPG123 client execution time was 00:04:00.3503365.
[9/16/2021 12:43:57 PM] ===============================================================================
[9/16/2021 12:43:57 PM]  Activating the epg123 client GUI. version 1.7.0.2
[9/16/2021 12:43:57 PM] ===============================================================================
[9/16/2021 12:43:57 PM] [ INFO] Successfully queried the Task Scheduler for status. Ready. Last Run 9/16/2021 4:30:00 AM; Exit: 0x00000000
[9/16/2021 12:44:18 PM] ===============================================================================
[9/16/2021 12:44:18 PM]  Activating the epg123 client GUI. version 1.7.0.2
[9/16/2021 12:44:18 PM] ===============================================================================
[9/16/2021 12:44:19 PM] [ INFO] Successfully queried the Task Scheduler for status. Ready. Last Run 9/16/2021 4:30:00 AM; Exit: 0x00000000
[9/16/2021 12:45:20 PM] ===============================================================================
[9/16/2021 12:45:20 PM]  Activating the epg123 configuration GUI. version 1.7.0.2
[9/16/2021 12:45:20 PM] ===============================================================================
[9/16/2021 12:45:21 PM] [ INFO] GetClientVersion() Unknown exception thrown. Message: The operation has timed out
[9/16/2021 12:45:22 PM] [ INFO] Token request successful. serverID: 20141201.web.1 , datetime: 2021-09-16T16:45:21Z
[9/16/2021 12:45:23 PM] [ INFO] Status request successful. account expires: 2022-02-11T18:22:35Z , lineups: 2/4 , lastDataUpdate: 2021-09-15T22:14:00Z
[9/16/2021 12:45:23 PM] [ INFO] system status: Online , message: No known issues.
[9/16/2021 12:45:23 PM] [ INFO] Successfully requested listing of subscribed lineups from Schedules Direct.
[9/16/2021 12:45:23 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-OTA-23233.
[9/16/2021 12:45:23 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-VA45521-X.
[9/16/2021 12:45:23 PM] [ INFO] Successfully queried the Task Scheduler for status. Ready. Last Run 9/16/2021 4:30:00 AM; Exit: 0x00000000
[9/16/2021 12:45:49 PM] ===============================================================================
[9/16/2021 12:45:49 PM]  Beginning epg123 update execution. version 1.7.0.2
[9/16/2021 12:45:49 PM] ===============================================================================
[9/16/2021 12:45:49 PM] [ INFO] Beginning EPG123 update execution. 2021-09-16 16:45:49Z
[9/16/2021 12:45:49 PM] [ INFO] DaysToDownload: 14 , TheTVDBNumbers : False , PrefixEpisodeTitle: True , PrefixEpisodeDescription : True , AppendEpisodeDesc: True , OADOverride : True , TMDbCoverArt: True , IncludeSDLogos : False , AutoAddNew: True , CreateXmltv: False , ModernMediaUiPlusSupport: False
[9/16/2021 12:45:49 PM] [ INFO] Status request successful. account expires: 2022-02-11T18:22:35Z , lineups: 2/4 , lastDataUpdate: 2021-09-15T22:14:00Z
[9/16/2021 12:45:49 PM] [ INFO] system status: Online , message: No known issues.
[9/16/2021 12:45:52 PM] [ INFO] Successfully retrieved TMDb configurations.
[9/16/2021 12:45:52 PM] [ INFO] Successfully requested listing of subscribed lineups from Schedules Direct.
[9/16/2021 12:45:52 PM] Entering BuildLineupServices() for 2 lineups.
[9/16/2021 12:45:52 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-OTA-23233.
[9/16/2021 12:45:52 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-VA45521-X.
[9/16/2021 12:45:52 PM] Exiting BuildLineupServices(). SUCCESS.
[9/16/2021 12:45:52 PM] Entering GetAllScheduleEntryMd5s() for 14 days on 380 stations.
[9/16/2021 12:45:56 PM] [ INFO] Successfully retrieved Md5s for 357 station's daily schedules. (0:00:00:03.7500052 /   532.240 KB)
[9/16/2021 12:45:56 PM] [ INFO] Successfully retrieved Md5s for  23 station's daily schedules. (0:00:00:00.3700005 /    34.305 KB)
[9/16/2021 12:45:56 PM] [ INFO] Found 5320 cached daily schedules.
[9/16/2021 12:45:57 PM] [ INFO] Downloaded 0 daily schedules.
[9/16/2021 12:45:59 PM] [ INFO] Processed 5320 daily schedules for 380 stations.
[9/16/2021 12:45:59 PM] Exiting GetAllScheduleEntryMd5s(). SUCCESS.
[9/16/2021 12:45:59 PM] Entering BuildAllProgramEntries() for 36115 programs.
[9/16/2021 12:46:06 PM] [ INFO] Found 36115 cached program descriptions.
[9/16/2021 12:46:06 PM] [ INFO] Processed 36115 program descriptions.
[9/16/2021 12:46:06 PM] Exiting BuildAllProgramEntries(). SUCCESS.
[9/16/2021 12:46:06 PM] Entering BuildAllGenericSeriesInfoDescriptions() for 4733 series.
[9/16/2021 12:46:06 PM] [ INFO] Found 4732 cached/unavailable series descriptions.
[9/16/2021 12:46:06 PM] [ INFO] Successfully retrieved   1 generic program descriptions. (0:00:00:00.0700001 /     3.000 B)
[9/16/2021 12:46:06 PM] [ INFO] GetGenericDescriptions() Unknown exception thrown. Message: Cannot deserialize the current JSON array (e.g. [1,2,3]) into type 'System.Collections.Generic.Dictionary`2[System.String,epg123.SchedulesDirect.GenericDescription]' because the type requires a JSON object (e.g. {"name":"value"}) to deserialize correctly.
To fix this error either change the JSON to a JSON object (e.g. {"name":"value"}) or change the deserialized type to an array or a type that implements a collection interface (e.g. ICollection, IList) like List<T> that can be deserialized from a JSON array. JsonArrayAttribute can also be added to the type to force it to deserialize from a JSON array.
Path '', line 1, position 1.
[9/16/2021 12:46:06 PM] [ INFO] Problem occurred during BuildGenericSeriesInfoDescriptions(). Did not process all series descriptions.
[9/16/2021 12:46:06 PM] [ INFO] Processed 4732 series descriptions.
[9/16/2021 12:46:06 PM] Exiting BuildAllGenericSeriesInfoDescriptions(). SUCCESS.
[9/16/2021 12:46:06 PM] Entering GetAllMoviePosters() for 2777 movies.
[9/16/2021 12:46:06 PM] [ INFO] Found 2777 cached/unavailable movie poster links.
[9/16/2021 12:46:06 PM] [ INFO] Processed 2777 movie poster links.
[9/16/2021 12:46:06 PM] Exiting GetAllMoviePosters(). SUCCESS.
[9/16/2021 12:46:06 PM] Entering GetAllSeriesImages() for 4733 series.
[9/16/2021 12:46:06 PM] [ INFO] Found 4588 cached/unavailable series image links.
[9/16/2021 12:46:07 PM] [ INFO] Successfully retrieved artwork info for 145 programs. (0:00:00:00.3700005 /    77.776 KB)
[9/16/2021 12:46:07 PM] [ INFO] Processed 4733 series image links.
[9/16/2021 12:46:07 PM] Exiting GetAllSeriesImages(). SUCCESS.
[9/16/2021 12:46:07 PM] Entering GetAllSeasonImages() for 4479 seasons.
[9/16/2021 12:46:07 PM] [ INFO] Found 4405 cached/unavailable season image links.
[9/16/2021 12:46:07 PM] [ INFO] Successfully retrieved artwork info for  74 programs. (0:00:00:00.2400004 /   429.521 KB)
[9/16/2021 12:46:07 PM] [ INFO] Processed 4479 season image links.
[9/16/2021 12:46:07 PM] Exiting GetAllSeasonImages(). SUCCESS.
[9/16/2021 12:46:07 PM] Entering GetAllSportsImages() for 0 sports events.
[9/16/2021 12:46:07 PM] [ INFO] Found 0 cached/unavailable sport event image links.
[9/16/2021 12:46:07 PM] [ INFO] Processed 0 sport event image links.
[9/16/2021 12:46:07 PM] Exiting GetAllSportsImages(). SUCCESS.
[9/16/2021 12:46:07 PM] [ INFO] Completed compiling keywords and keyword groups.
[9/16/2021 12:46:10 PM] [ INFO] Completed save of the MXF file to "C:\epg123\output\epg123.mxf".
[9/16/2021 12:46:10 PM] [ INFO] 26 entries deleted from the cache file during cleanup.
[9/16/2021 12:46:11 PM] [ INFO] Downloaded and processed     1.049 MB of data from Schedules Direct.
[9/16/2021 12:46:11 PM] [ INFO] Generated .mxf file contains 380 services, 4733 series, 4479 seasons, 36115 programs, 132059 schedule entries, and 56470 people with 8684 image links.
[9/16/2021 12:46:11 PM] [ INFO] Completed EPG123 update execution. SUCCESS.
[9/16/2021 12:46:11 PM] [ INFO] epg123 update execution time was 00:00:22.6800318.
[9/16/2021 12:46:12 PM] ===============================================================================
[9/16/2021 12:46:12 PM]  Beginning epg123 client execution. version 1.7.0.2
[9/16/2021 12:46:12 PM] ===============================================================================
[9/16/2021 12:46:12 PM] [ INFO] Beginning epg123 client execution. 2021-09-16 16:46:12Z
[9/16/2021 12:46:12 PM] [ INFO] Import: True , Match: True , NoLogo: False , Force: False , ShowProgress: True , NoGC: True , NoVerify: True , Verbose: False
[9/16/2021 12:46:15 PM] Entering ImportMxfFile() for file "c:\epg123\output\epg123.mxf".
[9/16/2021 12:52:32 PM] ===============================================================================
[9/16/2021 12:52:32 PM] [ERROR] An instance of epg123 is already running. Aborting.
[9/16/2021 12:52:32 PM] ===============================================================================
[9/16/2021 12:54:00 PM] [ INFO] Unhandled Exception: System.ObjectDisposedException: Cannot access a disposed object.
[9/16/2021 12:54:00 PM] [ INFO] Object name: 'ObjectStoreEvents is null'.
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Store.CrashProtector.ThrowObjectDisposed(String objectName)
[9/16/2021 12:54:00 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsEnumerator..ctor(ObjectStore store, WhereClause whereClause, ObjectType typeElem, ObjectType returnType, UpdateMode updateMode)
[9/16/2021 12:54:00 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsEnumerator..ctor(ObjectStore store, WhereClause whereClause, ObjectType typeElem)
[9/16/2021 12:54:00 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsRecord.NewEnumerator()
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Store.StoredObjects.GetStoredObjectsEnumerator[T]()
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Store.StoredObjects`1.System.Collections.Generic.IEnumerable<T>.GetEnumerator()
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.SchedulerCache.GetAssignmentGroups(RequestedProgram requestedProgram)
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.BranchAndBoundSchedulingStep.TryNext(PriorityListBase priorityList, PriorityQueue`1 queue, RequestedPrograms allRequestedPrograms, Int32 maxScheduleCount, Scheduler scheduler, BranchAndBoundSchedulingStep& next)
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.BranchAndBoundSchedulingAlgorithm(ScheduleBase schedule, PriorityListBase priorityList, IEnumerable`1 requestedPrograms, Int32 maxScheduleCount, ExpressionLockHandle`1 lockHandle)
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.<CreateSchedule>b__8(ScheduleParameters parameters, ExpressionLockHandle`1 lockHandle)
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.OptimisticReader`3.Read(ReaderAction2 readerAction, P parameter, Expression expression)
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.CreateSchedule(ScheduleBase schedule, PriorityListBase priorityList, IEnumerable`1 requestedPrograms)
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.Schedule(IEnumerable`1 requestedPrograms)
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.Schedule()
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Requests.UpdateAllActive(ObjectStore objectStore)
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.FullRescheduleWorker.Work()
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.SignaledWorker.ThreadProcess()
[9/16/2021 12:54:00 PM] [ INFO]    at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state)
[9/16/2021 12:54:00 PM] [ INFO]    at System.Threading.ThreadHelper.ThreadStart()
[9/16/2021 12:54:00 PM] [ERROR] Error using loadmxf.exe to import new guide information. Exit code: -532459699
[9/16/2021 12:54:00 PM] Exiting ImportMxfFile(). FAILURE.
[9/16/2021 12:54:00 PM] [ERROR] Failed to import .mxf file. Exiting.
[9/16/2021 12:54:00 PM] [ INFO] MXF file was created on 9/16/2021 12:45:49 PM
[9/16/2021 12:54:00 PM] [ INFO] Completed EPG123 client execution.
[9/16/2021 12:54:00 PM] [ INFO] EPG123 client execution time was 00:07:48.9134751.
[9/16/2021 1:02:36 PM] ===============================================================================
[9/16/2021 1:02:36 PM]  Beginning epg123 update execution. version 1.7.0.2
[9/16/2021 1:02:36 PM] ===============================================================================
[9/16/2021 1:02:36 PM] [ INFO] Beginning EPG123 update execution. 2021-09-16 17:02:36Z
[9/16/2021 1:02:36 PM] [ INFO] DaysToDownload: 14 , TheTVDBNumbers : False , PrefixEpisodeTitle: True , PrefixEpisodeDescription : True , AppendEpisodeDesc: True , OADOverride : True , TMDbCoverArt: True , IncludeSDLogos : False , AutoAddNew: True , CreateXmltv: False , ModernMediaUiPlusSupport: False
[9/16/2021 1:02:36 PM] [ INFO] Status request successful. account expires: 2022-02-11T18:22:35Z , lineups: 2/4 , lastDataUpdate: 2021-09-15T22:14:00Z
[9/16/2021 1:02:36 PM] [ INFO] system status: Online , message: No known issues.
[9/16/2021 1:02:37 PM] [ INFO] GetClientVersion() Unknown exception thrown. Message: The operation has timed out
[9/16/2021 1:02:39 PM] [ INFO] Successfully retrieved TMDb configurations.
[9/16/2021 1:02:39 PM] [ INFO] Successfully requested listing of subscribed lineups from Schedules Direct.
[9/16/2021 1:02:39 PM] Entering BuildLineupServices() for 2 lineups.
[9/16/2021 1:02:39 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-OTA-23233.
[9/16/2021 1:02:39 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-VA45521-X.
[9/16/2021 1:02:39 PM] Exiting BuildLineupServices(). SUCCESS.
[9/16/2021 1:02:39 PM] Entering GetAllScheduleEntryMd5s() for 14 days on 380 stations.
[9/16/2021 1:02:44 PM] [ INFO] Successfully retrieved Md5s for 357 station's daily schedules. (0:00:00:04.2300059 /   532.240 KB)
[9/16/2021 1:02:44 PM] [ INFO] Successfully retrieved Md5s for  23 station's daily schedules. (0:00:00:00.3900005 /    34.305 KB)
[9/16/2021 1:02:44 PM] [ INFO] Found 5320 cached daily schedules.
[9/16/2021 1:02:44 PM] [ INFO] Downloaded 0 daily schedules.
[9/16/2021 1:02:47 PM] [ INFO] Processed 5320 daily schedules for 380 stations.
[9/16/2021 1:02:47 PM] Exiting GetAllScheduleEntryMd5s(). SUCCESS.
[9/16/2021 1:02:47 PM] Entering BuildAllProgramEntries() for 36115 programs.
[9/16/2021 1:02:52 PM] [ INFO] Found 36115 cached program descriptions.
[9/16/2021 1:02:52 PM] [ INFO] Processed 36115 program descriptions.
[9/16/2021 1:02:52 PM] Exiting BuildAllProgramEntries(). SUCCESS.
[9/16/2021 1:02:52 PM] Entering BuildAllGenericSeriesInfoDescriptions() for 4733 series.
[9/16/2021 1:02:52 PM] [ INFO] Found 4732 cached/unavailable series descriptions.
[9/16/2021 1:02:52 PM] [ INFO] Successfully retrieved   1 generic program descriptions. (0:00:00:00.0600001 /     3.000 B)
[9/16/2021 1:02:52 PM] [ INFO] GetGenericDescriptions() Unknown exception thrown. Message: Cannot deserialize the current JSON array (e.g. [1,2,3]) into type 'System.Collections.Generic.Dictionary`2[System.String,epg123.SchedulesDirect.GenericDescription]' because the type requires a JSON object (e.g. {"name":"value"}) to deserialize correctly.
To fix this error either change the JSON to a JSON object (e.g. {"name":"value"}) or change the deserialized type to an array or a type that implements a collection interface (e.g. ICollection, IList) like List<T> that can be deserialized from a JSON array. JsonArrayAttribute can also be added to the type to force it to deserialize from a JSON array.
Path '', line 1, position 1.
[9/16/2021 1:02:52 PM] [ INFO] Problem occurred during BuildGenericSeriesInfoDescriptions(). Did not process all series descriptions.
[9/16/2021 1:02:52 PM] [ INFO] Processed 4732 series descriptions.
[9/16/2021 1:02:52 PM] Exiting BuildAllGenericSeriesInfoDescriptions(). SUCCESS.
[9/16/2021 1:02:52 PM] Entering GetAllMoviePosters() for 2777 movies.
[9/16/2021 1:02:52 PM] [ INFO] Found 2777 cached/unavailable movie poster links.
[9/16/2021 1:02:52 PM] [ INFO] Processed 2777 movie poster links.
[9/16/2021 1:02:52 PM] Exiting GetAllMoviePosters(). SUCCESS.
[9/16/2021 1:02:52 PM] Entering GetAllSeriesImages() for 4733 series.
[9/16/2021 1:02:52 PM] [ INFO] Found 4588 cached/unavailable series image links.
[9/16/2021 1:02:53 PM] [ INFO] Successfully retrieved artwork info for 145 programs. (0:00:00:00.3400005 /    77.776 KB)
[9/16/2021 1:02:53 PM] [ INFO] Processed 4733 series image links.
[9/16/2021 1:02:53 PM] Exiting GetAllSeriesImages(). SUCCESS.
[9/16/2021 1:02:53 PM] Entering GetAllSeasonImages() for 4479 seasons.
[9/16/2021 1:02:53 PM] [ INFO] Found 4405 cached/unavailable season image links.
[9/16/2021 1:02:53 PM] [ INFO] Successfully retrieved artwork info for  74 programs. (0:00:00:00.3300005 /   429.521 KB)
[9/16/2021 1:02:53 PM] [ INFO] Processed 4479 season image links.
[9/16/2021 1:02:53 PM] Exiting GetAllSeasonImages(). SUCCESS.
[9/16/2021 1:02:53 PM] Entering GetAllSportsImages() for 0 sports events.
[9/16/2021 1:02:53 PM] [ INFO] Found 0 cached/unavailable sport event image links.
[9/16/2021 1:02:53 PM] [ INFO] Processed 0 sport event image links.
[9/16/2021 1:02:53 PM] Exiting GetAllSportsImages(). SUCCESS.
[9/16/2021 1:02:53 PM] [ INFO] Completed compiling keywords and keyword groups.
[9/16/2021 1:02:56 PM] [ INFO] Completed save of the MXF file to "C:\epg123\output\epg123.mxf".
[9/16/2021 1:02:56 PM] [ INFO] 48 entries deleted from the cache file during cleanup.
[9/16/2021 1:02:57 PM] [ INFO] Downloaded and processed     1.049 MB of data from Schedules Direct.
[9/16/2021 1:02:57 PM] [ INFO] Generated .mxf file contains 380 services, 4733 series, 4479 seasons, 36115 programs, 132059 schedule entries, and 56470 people with 8684 image links.
[9/16/2021 1:02:57 PM] [ INFO] Completed EPG123 update execution. SUCCESS.
[9/16/2021 1:02:58 PM] [ INFO] epg123 update execution time was 00:00:21.9500308.
[9/16/2021 1:02:58 PM] ===============================================================================
[9/16/2021 1:02:58 PM]  Beginning epg123 client execution. version 1.7.0.2
[9/16/2021 1:02:58 PM] ===============================================================================
[9/16/2021 1:02:58 PM] [ INFO] Beginning epg123 client execution. 2021-09-16 17:02:58Z
[9/16/2021 1:02:58 PM] [ INFO] Import: True , Match: True , NoLogo: False , Force: False , ShowProgress: False , NoGC: True , NoVerify: False , Verbose: False
[9/16/2021 1:03:02 PM] Entering ImportMxfFile() for file "c:\epg123\output\epg123.mxf".
[9/16/2021 1:10:55 PM] [ INFO] Unhandled Exception: System.ObjectDisposedException: Cannot access a disposed object.
[9/16/2021 1:10:55 PM] [ INFO] Object name: 'ObjectStoreEvents is null'.
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Store.CrashProtector.ThrowObjectDisposed(String objectName)
[9/16/2021 1:10:55 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsEnumerator..ctor(ObjectStore store, WhereClause whereClause, ObjectType typeElem, ObjectType returnType, UpdateMode updateMode)
[9/16/2021 1:10:55 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsEnumerator..ctor(ObjectStore store, WhereClause whereClause, ObjectType typeElem)
[9/16/2021 1:10:55 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsRecord.NewEnumerator()
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Store.StoredObjects.GetStoredObjectsEnumerator[T]()
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Store.StoredObjects`1.System.Collections.Generic.IEnumerable<T>.GetEnumerator()
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.SchedulerCache.GetAssignmentGroups(RequestedProgram requestedProgram)
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.BranchAndBoundSchedulingStep.TryNext(PriorityListBase priorityList, PriorityQueue`1 queue, RequestedPrograms allRequestedPrograms, Int32 maxScheduleCount, Scheduler scheduler, BranchAndBoundSchedulingStep& next)
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.BranchAndBoundSchedulingAlgorithm(ScheduleBase schedule, PriorityListBase priorityList, IEnumerable`1 requestedPrograms, Int32 maxScheduleCount, ExpressionLockHandle`1 lockHandle)
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.<CreateSchedule>b__8(ScheduleParameters parameters, ExpressionLockHandle`1 lockHandle)
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.OptimisticReader`3.Read(ReaderAction2 readerAction, P parameter, Expression expression)
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.CreateSchedule(ScheduleBase schedule, PriorityListBase priorityList, IEnumerable`1 requestedPrograms)
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.Schedule(IEnumerable`1 requestedPrograms)
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.Schedule()
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Requests.UpdateAllActive(ObjectStore objectStore)
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.FullRescheduleWorker.Work()
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.SignaledWorker.ThreadProcess()
[9/16/2021 1:10:55 PM] [ INFO]    at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state)
[9/16/2021 1:10:55 PM] [ INFO]    at System.Threading.ThreadHelper.ThreadStart()
[9/16/2021 1:10:55 PM] [ERROR] Error using loadmxf.exe to import new guide information. Exit code: -532459699
[9/16/2021 1:10:55 PM] Exiting ImportMxfFile(). FAILURE.
[9/16/2021 1:10:55 PM] [ERROR] Failed to import .mxf file. Exiting.
[9/16/2021 1:10:56 PM] [ INFO] MXF file was created on 9/16/2021 1:02:36 PM
[9/16/2021 1:10:56 PM] [ INFO] Completed EPG123 client execution.
[9/16/2021 1:10:57 PM] [ INFO] EPG123 client execution time was 00:07:58.4847024.
[9/16/2021 1:47:00 PM] ===============================================================================
[9/16/2021 1:47:00 PM]  Activating the epg123 client GUI. version 1.7.0.2
[9/16/2021 1:47:00 PM] ===============================================================================
[9/16/2021 1:47:00 PM] [ INFO] Successfully queried the Task Scheduler for status. Ready. Last Run 9/16/2021 1:02:35 PM; Exit: 0x00000000
[9/16/2021 1:47:45 PM] ===============================================================================
[9/16/2021 1:47:45 PM]  Beginning epg123 update execution. version 1.7.0.2
[9/16/2021 1:47:45 PM] ===============================================================================
[9/16/2021 1:47:45 PM] [ INFO] Beginning EPG123 update execution. 2021-09-16 17:47:45Z
[9/16/2021 1:47:45 PM] [ INFO] DaysToDownload: 14 , TheTVDBNumbers : False , PrefixEpisodeTitle: True , PrefixEpisodeDescription : True , AppendEpisodeDesc: True , OADOverride : True , TMDbCoverArt: True , IncludeSDLogos : False , AutoAddNew: True , CreateXmltv: False , ModernMediaUiPlusSupport: False
[9/16/2021 1:47:47 PM] [ INFO] Status request successful. account expires: 2022-02-11T18:22:35Z , lineups: 2/4 , lastDataUpdate: 2021-09-16T17:12:15Z
[9/16/2021 1:47:47 PM] [ INFO] system status: Online , message: No known issues.
[9/16/2021 1:47:49 PM] [ INFO] GetClientVersion() Unknown exception thrown. Message: The operation has timed out
[9/16/2021 1:47:52 PM] [ INFO] Successfully retrieved TMDb configurations.
[9/16/2021 1:47:52 PM] [ INFO] Successfully requested listing of subscribed lineups from Schedules Direct.
[9/16/2021 1:47:52 PM] Entering BuildLineupServices() for 2 lineups.
[9/16/2021 1:47:53 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-OTA-23233.
[9/16/2021 1:47:53 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-VA45521-X.
[9/16/2021 1:47:53 PM] Exiting BuildLineupServices(). SUCCESS.
[9/16/2021 1:47:53 PM] Entering GetAllScheduleEntryMd5s() for 14 days on 380 stations.
[9/16/2021 1:47:56 PM] [ INFO] Successfully retrieved Md5s for 357 station's daily schedules. (0:00:00:02.8500040 /   532.264 KB)
[9/16/2021 1:47:56 PM] [WARNG] Duplicate schedule Md5 return for stationId 58649 (FBN) on 2021-09-29 with 2021-09-22.
[9/16/2021 1:47:56 PM] [WARNG] Removing duplicate Md5 schedule entry for stationId 58649 (FBN) on 2021-09-22.
[9/16/2021 1:47:56 PM] [WARNG] Duplicate schedule Md5 return for stationId 58718 (FBNHD) on 2021-09-29 with 2021-09-22.
[9/16/2021 1:47:56 PM] [WARNG] Removing duplicate Md5 schedule entry for stationId 58718 (FBNHD) on 2021-09-22.
[9/16/2021 1:48:00 PM] [ INFO] Successfully retrieved 225 station's daily schedules.          (0:00:00:04.3700061 /     6.211 MB)
[9/16/2021 1:48:02 PM] [WARNG] Md5 mismatch for station 58649 on 2021-09-22. Downloaded y1RWbFwmPyLWqjaPOEFWQg
[9/16/2021 1:48:02 PM] [WARNG] Md5 mismatch for station 58649 on 2021-09-29. Downloaded y1RWbFwmPyLWqjaPOEFWQg
[9/16/2021 1:48:03 PM] [WARNG] Md5 mismatch for station 58718 on 2021-09-22. Downloaded gjdP4Pps0Yx+nS0SnjfljQ
[9/16/2021 1:48:03 PM] [WARNG] Md5 mismatch for station 58718 on 2021-09-29. Downloaded gjdP4Pps0Yx+nS0SnjfljQ
[9/16/2021 1:48:05 PM] [ INFO] Successfully retrieved Md5s for  23 station's daily schedules. (0:00:00:00.3500005 /    34.302 KB)
[9/16/2021 1:48:06 PM] [ INFO] Successfully retrieved  13 station's daily schedules.          (0:00:00:00.4600006 /   464.055 KB)
[9/16/2021 1:48:06 PM] [ INFO] Found 4222 cached daily schedules.
[9/16/2021 1:48:06 PM] [ INFO] Downloaded 1098 daily schedules.
[9/16/2021 1:48:09 PM] [ INFO] Processed 5316 daily schedules for 380 stations.
[9/16/2021 1:48:09 PM] Exiting GetAllScheduleEntryMd5s(). SUCCESS.
[9/16/2021 1:48:09 PM] Entering BuildAllProgramEntries() for 36535 programs.
[9/16/2021 1:48:15 PM] [ INFO] Found 35588 cached program descriptions.
[9/16/2021 1:48:19 PM] [ INFO] Successfully retrieved  947 program descriptions. (0:00:00:04.4800063 /     1.841 MB)
[9/16/2021 1:48:20 PM] [ INFO] Processed 36535 program descriptions.
[9/16/2021 1:48:20 PM] Exiting BuildAllProgramEntries(). SUCCESS.
[9/16/2021 1:48:20 PM] Entering BuildAllGenericSeriesInfoDescriptions() for 4761 series.
[9/16/2021 1:48:20 PM] [ INFO] Found 4741 cached/unavailable series descriptions.
[9/16/2021 1:48:20 PM] [ INFO] Successfully retrieved  20 generic program descriptions. (0:00:00:00.2800004 /     5.668 KB)
[9/16/2021 1:48:20 PM] [ INFO] Problem occurred during BuildGenericSeriesInfoDescriptions(). Did not process all series descriptions.
[9/16/2021 1:48:20 PM] [ INFO] Processed 4760 series descriptions.
[9/16/2021 1:48:20 PM] Exiting BuildAllGenericSeriesInfoDescriptions(). SUCCESS.
[9/16/2021 1:48:20 PM] Entering GetAllMoviePosters() for 2783 movies.
[9/16/2021 1:48:20 PM] [ INFO] Found 2740 cached/unavailable movie poster links.
[9/16/2021 1:48:21 PM] [ INFO] Successfully retrieved artwork info for  43 programs. (0:00:00:00.3000004 /   605.621 KB)
[9/16/2021 1:48:21 PM] [ INFO] TMDb catalog search for "Neh" from 2021 found 1 results.
[9/16/2021 1:48:21 PM] [ INFO] TMDb catalog search for "Little Sky" from 2021 found 1 results.
[9/16/2021 1:48:21 PM] [ INFO] TMDb catalog search for "Free Fall" from 1999 found 1 results.
[9/16/2021 1:48:21 PM] [ INFO] Processed 2783 movie poster links.
[9/16/2021 1:48:21 PM] Exiting GetAllMoviePosters(). SUCCESS.
[9/16/2021 1:48:21 PM] Entering GetAllSeriesImages() for 4761 series.
[9/16/2021 1:48:21 PM] [ INFO] Found 4563 cached/unavailable series image links.
[9/16/2021 1:48:22 PM] [ INFO] Successfully retrieved artwork info for 198 programs. (0:00:00:00.5600008 /   399.433 KB)
[9/16/2021 1:48:22 PM] [ INFO] Processed 4761 series image links.
[9/16/2021 1:48:22 PM] Exiting GetAllSeriesImages(). SUCCESS.
[9/16/2021 1:48:22 PM] Entering GetAllSeasonImages() for 4500 seasons.
[9/16/2021 1:48:22 PM] [ INFO] Found 4419 cached/unavailable season image links.
[9/16/2021 1:48:22 PM] [ INFO] Successfully retrieved artwork info for  81 programs. (0:00:00:00.3300004 /   512.028 KB)
[9/16/2021 1:48:22 PM] [ INFO] Processed 4500 season image links.
[9/16/2021 1:48:22 PM] Exiting GetAllSeasonImages(). SUCCESS.
[9/16/2021 1:48:22 PM] Entering GetAllSportsImages() for 0 sports events.
[9/16/2021 1:48:22 PM] [ INFO] Found 0 cached/unavailable sport event image links.
[9/16/2021 1:48:22 PM] [ INFO] Processed 0 sport event image links.
[9/16/2021 1:48:22 PM] Exiting GetAllSportsImages(). SUCCESS.
[9/16/2021 1:48:23 PM] [ INFO] Completed compiling keywords and keyword groups.
[9/16/2021 1:48:25 PM] [ INFO] Completed save of the MXF file to "C:\epg123\output\epg123.mxf".
[9/16/2021 1:48:25 PM] [ INFO] 1686 entries deleted from the cache file during cleanup.
[9/16/2021 1:48:27 PM] [ INFO] Downloaded and processed    10.546 MB of data from Schedules Direct.
[9/16/2021 1:48:27 PM] [ INFO] Generated .mxf file contains 380 services, 4761 series, 4500 seasons, 36535 programs, 131834 schedule entries, and 56746 people with 8704 image links.
[9/16/2021 1:48:27 PM] [ INFO] Completed EPG123 update execution. SUCCESS.
[9/16/2021 1:48:27 PM] [ INFO] epg123 update execution time was 00:00:42.1480728.
[9/16/2021 1:48:27 PM] ===============================================================================
[9/16/2021 1:48:27 PM]  Beginning epg123 client execution. version 1.7.0.2
[9/16/2021 1:48:27 PM] ===============================================================================
[9/16/2021 1:48:27 PM] [ INFO] Beginning epg123 client execution. 2021-09-16 17:48:27Z
[9/16/2021 1:48:27 PM] [ INFO] Import: True , Match: True , NoLogo: False , Force: False , ShowProgress: False , NoGC: True , NoVerify: False , Verbose: False
[9/16/2021 1:48:32 PM] Entering ImportMxfFile() for file "c:\epg123\output\epg123.mxf".
[9/16/2021 1:57:56 PM] [ INFO] Unhandled Exception: System.ObjectDisposedException: Cannot access a disposed object.
[9/16/2021 1:57:56 PM] [ INFO] Object name: 'ObjectStoreEvents is null'.
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Store.CrashProtector.ThrowObjectDisposed(String objectName)
[9/16/2021 1:57:56 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsEnumerator..ctor(ObjectStore store, WhereClause whereClause, ObjectType typeElem, ObjectType returnType, UpdateMode updateMode)
[9/16/2021 1:57:56 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsEnumerator..ctor(ObjectStore store, WhereClause whereClause, ObjectType typeElem)
[9/16/2021 1:57:56 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsRecord.NewEnumerator()
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Store.StoredObjects.GetStoredObjectsEnumerator[T]()
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Store.StoredObjects`1.System.Collections.Generic.IEnumerable<T>.GetEnumerator()
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.SchedulerCache.GetAssignmentGroups(RequestedProgram requestedProgram)
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.BranchAndBoundSchedulingStep.TryNext(PriorityListBase priorityList, PriorityQueue`1 queue, RequestedPrograms allRequestedPrograms, Int32 maxScheduleCount, Scheduler scheduler, BranchAndBoundSchedulingStep& next)
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.BranchAndBoundSchedulingAlgorithm(ScheduleBase schedule, PriorityListBase priorityList, IEnumerable`1 requestedPrograms, Int32 maxScheduleCount, ExpressionLockHandle`1 lockHandle)
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.<CreateSchedule>b__8(ScheduleParameters parameters, ExpressionLockHandle`1 lockHandle)
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.OptimisticReader`3.Read(ReaderAction2 readerAction, P parameter, Expression expression)
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.CreateSchedule(ScheduleBase schedule, PriorityListBase priorityList, IEnumerable`1 requestedPrograms)
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.Schedule(IEnumerable`1 requestedPrograms)
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.Schedule()
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Requests.UpdateAllActive(ObjectStore objectStore)
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.FullRescheduleWorker.Work()
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.SignaledWorker.ThreadProcess()
[9/16/2021 1:57:56 PM] [ INFO]    at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state)
[9/16/2021 1:57:56 PM] [ INFO]    at System.Threading.ThreadHelper.ThreadStart()
[9/16/2021 1:57:56 PM] [ERROR] Error using loadmxf.exe to import new guide information. Exit code: -532459699
[9/16/2021 1:57:56 PM] Exiting ImportMxfFile(). FAILURE.
[9/16/2021 1:57:56 PM] [ERROR] Failed to import .mxf file. Exiting.
[9/16/2021 1:57:56 PM] [ INFO] MXF file was created on 9/16/2021 1:47:45 PM
[9/16/2021 1:57:56 PM] [WARNG] The imported MXF file contained a WARNING in its status field.
[9/16/2021 1:57:56 PM] [ INFO] Completed EPG123 client execution.
[9/16/2021 1:57:56 PM] [ INFO] EPG123 client execution time was 00:09:28.6711036.
Ran it from the GUI and get "Windows Media Center MXF Loader has stopped working"

Code: Select all

Description:
  Stopped working

Problem signature:
  Problem Event Name:	CLR20r3
  Problem Signature 01:	loadmxf.exe
  Problem Signature 02:	6.1.7600.16385
  Problem Signature 03:	4a5bb41e
  Problem Signature 04:	mcstore
  Problem Signature 05:	6.1.0.0
  Problem Signature 06:	4ce7b873
  Problem Signature 07:	328
  Problem Signature 08:	19
  Problem Signature 09:	System.ObjectDisposedException
  OS Version:	6.1.7601.2.1.0.256.1
  Locale ID:	1033
Top
User avatar
garyan2

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

HTPC Specs: Show details

#22

Post by garyan2 » Thu Sep 16, 2021 8:03 pm

IT Troll wrote: ↑Thu Sep 16, 2021 5:02 pm The registry existed and contained a token. Expiry was later tonight, ~24 hours after I upgraded to this version.

I restarted the service which then resulted in:

Code: Select all

[16/09/2021 17:43:25] Refreshed token upon receiving an UNKNOWN_USER (5004) error code.
Guide images are mostly appearing OK now. However, they are missing for some shows.

Code: Select all

[16/09/2021 17:43:25] /image/71d634446a438882e1f0df956fab67ccc70eb8f7ac50d942406c57c3c5685b67.jpg:
{"response":"IMAGE_NOT_FOUND","code":5000,"serverID":"20141201.web.1","message":"Could not find requested image. Post message to http:\/\/forums.schedulesdirect.org\/viewforum.php?f=6 if you are having issues.","datetime":"2021-09-16T16:43:25Z"}
[16/09/2021 17:43:26] /image/8649a51bea18f593f134f645189c87b3ff593d9c6931729e05eccdd32dd3facf.jpg:
{"response":"IMAGE_NOT_FOUND","code":5000,"serverID":"20141201.web.1","message":"Could not find requested image. Post message to http:\/\/forums.schedulesdirect.org\/viewforum.php?f=6 if you are having issues.","datetime":"2021-09-16T16:43:26Z"}
[16/09/2021 17:46:36] /image/0f492810243f844e76390e8a6e55786611231ec2e66b2d3b8118c17cada078f7.jpg:
{"response":"IMAGE_NOT_FOUND","code":5000,"serverID":"20141201.web.1","message":"Could not find requested image. Post message to http:\/\/forums.schedulesdirect.org\/viewforum.php?f=6 if you are having issues.","datetime":"2021-09-16T16:46:36Z"}
These are for quite well known shows which I am fairly sure had images previously. BTW, I don't currently have caching enabled (stand-alone full mode setup).
The initial problem I believe is the sequence of the installer. If the service starts prior to the registry keys existing, then no matter the settings it will not cache or auto refresh. The service would have to be stopped and restarted. I'll see what I can do with the installer for this.

For the IMAGE_NOT_FOUND, this will hopefully be corrected soon, but SD is having a hard time with the upstream source to get the images where they need to be. Basically, Gracenote is providing "different" images for some series which means the filename will change. Since EPG123 has the image link cached, it tries to get the old image and gets the error. This is a daily thing with multiple series and not something we can do on our side. To get the most up-to-date links, you will have to clear your json cache in the configuration GUI and build a new MXF.
- Gary
Keeping WMC alive beyond January 2020. https://garyan2.github.io
Top
User avatar
garyan2

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

HTPC Specs: Show details

#23

Post by garyan2 » Thu Sep 16, 2021 8:26 pm

cbarbie wrote: ↑Thu Sep 16, 2021 6:05 pm I updated this afternoon and ran the update 3 times. Each time the XML load errors "Unhandled Exception: System.ObjectDisposedException: Cannot access a disposed object."
Everthing ran fine with 1.6.7.0. Run log for all 4 shown below. Rebooted 3 times.

Win7 PC running WMC

Code: Select all

[9/16/2021 4:30:00 AM] ===============================================================================
[9/16/2021 4:30:00 AM]  Beginning epg123 update execution. version 1.6.7.0
[9/16/2021 4:30:00 AM] ===============================================================================
[9/16/2021 4:30:00 AM] [ INFO] Beginning EPG123 update execution. 2021-09-16 08:30:00Z
[9/16/2021 4:30:00 AM] [ INFO] DaysToDownload: 14 , TheTVDBNumbers : False , PrefixEpisodeTitle: True , PrefixEpisodeDescription : True , AppendEpisodeDesc: True , OADOverride : True , TMDbCoverArt: True , IncludeSDLogos : False , AutoAddNew: True , CreateXmltv: False , ModernMediaUiPlusSupport: False
[9/16/2021 4:30:01 AM] [ INFO] Token request successful. serverID: 20141201.web.1 , datetime: 2021-09-16T08:29:59Z
[9/16/2021 4:30:01 AM] [ INFO] Status request successful. account expires: 2022-02-11T18:22:35Z , lineups: 2/4 , lastDataUpdate: 2021-09-15T22:14:00Z
[9/16/2021 4:30:01 AM] [ INFO] system status: Online , message: No known issues.
[9/16/2021 4:30:03 AM] [ INFO] GetClientVersion() Unknown exception thrown. Message: The operation has timed out
[9/16/2021 4:30:04 AM] [ INFO] Successfully retrieved TMDb configurations.
[9/16/2021 4:30:04 AM] [ INFO] Successfully requested listing of subscribed lineups from Schedules Direct.
[9/16/2021 4:30:04 AM] Entering BuildLineupServices() for 2 lineups.
[9/16/2021 4:30:04 AM] [ INFO] Successfully retrieved the station mapping for lineup USA-OTA-23233.
[9/16/2021 4:30:05 AM] [ INFO] Successfully retrieved the station mapping for lineup USA-VA45521-X.
[9/16/2021 4:30:05 AM] Exiting BuildLineupServices(). SUCCESS.
[9/16/2021 4:30:05 AM] Entering GetAllScheduleEntryMd5s() for 14 days on 380 stations.
[9/16/2021 4:30:11 AM] [ INFO] Successfully retrieved Md5s for 357 station's daily schedules. (0:00:00:05.9300083 /   532.240 KB)
[9/16/2021 4:30:18 AM] [ INFO] Successfully retrieved 357 station's daily schedules.          (0:00:00:06.7200094 /     4.712 MB)
[9/16/2021 4:30:21 AM] [ INFO] Successfully retrieved Md5s for  23 station's daily schedules. (0:00:00:00.3000004 /    34.305 KB)
[9/16/2021 4:30:21 AM] [ INFO] Successfully retrieved  23 station's daily schedules.          (0:00:00:00.2500003 /   218.222 KB)
[9/16/2021 4:30:22 AM] [ INFO] Found 4436 cached daily schedules.
[9/16/2021 4:30:22 AM] [ INFO] Downloaded 884 daily schedules.
[9/16/2021 4:30:24 AM] [ INFO] Processed 5320 daily schedules for 380 stations.
[9/16/2021 4:30:24 AM] Exiting GetAllScheduleEntryMd5s(). SUCCESS.
[9/16/2021 4:30:24 AM] Entering BuildAllProgramEntries() for 36115 programs.
[9/16/2021 4:30:29 AM] [ INFO] Found 33927 cached program descriptions.
[9/16/2021 4:30:38 AM] [ INFO] Successfully retrieved 2188 program descriptions. (0:00:00:09.0000126 /     4.193 MB)
[9/16/2021 4:30:40 AM] [ INFO] Processed 36115 program descriptions.
[9/16/2021 4:30:40 AM] Exiting BuildAllProgramEntries(). SUCCESS.
[9/16/2021 4:30:40 AM] Entering BuildAllGenericSeriesInfoDescriptions() for 4733 series.
[9/16/2021 4:30:40 AM] [ INFO] Found 4691 cached/unavailable series descriptions.
[9/16/2021 4:30:40 AM] [ INFO] Successfully retrieved  42 generic program descriptions. (0:00:00:00.3100004 /    11.886 KB)
[9/16/2021 4:30:40 AM] [ INFO] Problem occurred during BuildGenericSeriesInfoDescriptions(). Did not process all series descriptions.
[9/16/2021 4:30:40 AM] [ INFO] Processed 4732 series descriptions.
[9/16/2021 4:30:40 AM] Exiting BuildAllGenericSeriesInfoDescriptions(). SUCCESS.
[9/16/2021 4:30:40 AM] Entering GetAllMoviePosters() for 2777 movies.
[9/16/2021 4:30:40 AM] [ INFO] Found 2694 cached/unavailable movie poster links.
[9/16/2021 4:30:41 AM] [ INFO] Successfully retrieved artwork info for  83 programs. (0:00:00:00.4800006 /     1.011 MB)
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "The Death of weener Long" from 2019 found 1 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "Scary Movie 5" from 2013 found 2 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "A Hijacking" from 2012 found 1 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "Murdered" from 2012 found 4 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "My Neighbour" from 2012 found 1 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "Sacrifice" from 2020 found 9 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "The Freshman" from 1925 found 1 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "City Lights" from 1931 found 1 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "Flesh and the Devil" from 1926 found 1 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "Hear My Song" from 1991 found 1 results.
[9/16/2021 4:30:41 AM] [ INFO] TMDb catalog search for "Promises in the Dark" from 1979 found 1 results.
[9/16/2021 4:30:42 AM] [ INFO] TMDb catalog search for "Network" from 1976 found 1 results.
[9/16/2021 4:30:42 AM] [ INFO] Processed 2777 movie poster links.
[9/16/2021 4:30:42 AM] Exiting GetAllMoviePosters(). SUCCESS.
[9/16/2021 4:30:42 AM] Entering GetAllSeriesImages() for 4733 series.
[9/16/2021 4:30:42 AM] [ INFO] Found 4488 cached/unavailable series image links.
[9/16/2021 4:30:42 AM] [ INFO] Successfully retrieved artwork info for 245 programs. (0:00:00:00.7000010 /   904.887 KB)
[9/16/2021 4:30:43 AM] [ INFO] Processed 4733 series image links.
[9/16/2021 4:30:43 AM] Exiting GetAllSeriesImages(). SUCCESS.
[9/16/2021 4:30:43 AM] Entering GetAllSeasonImages() for 4479 seasons.
[9/16/2021 4:30:43 AM] [ INFO] Found 4380 cached/unavailable season image links.
[9/16/2021 4:30:43 AM] [ INFO] Successfully retrieved artwork info for  99 programs. (0:00:00:00.4400007 /   768.244 KB)
[9/16/2021 4:30:43 AM] [ INFO] Processed 4479 season image links.
[9/16/2021 4:30:43 AM] Exiting GetAllSeasonImages(). SUCCESS.
[9/16/2021 4:30:43 AM] Entering GetAllSportsImages() for 0 sports events.
[9/16/2021 4:30:43 AM] [ INFO] Found 0 cached/unavailable sport event image links.
[9/16/2021 4:30:43 AM] [ INFO] Processed 0 sport event image links.
[9/16/2021 4:30:43 AM] Exiting GetAllSportsImages(). SUCCESS.
[9/16/2021 4:30:43 AM] [ INFO] Completed compiling keywords and keyword groups.
[9/16/2021 4:30:46 AM] [ INFO] Completed save of the MXF file to "C:\epg123\output\epg123.mxf".
[9/16/2021 4:30:46 AM] [ INFO] 3667 entries deleted from the cache file during cleanup.
[9/16/2021 4:30:47 AM] [ INFO] Downloaded and processed    12.328 MB of data from Schedules Direct.
[9/16/2021 4:30:47 AM] [ INFO] Generated .mxf file contains 380 services, 4733 series, 4479 seasons, 36115 programs, 132059 schedule entries, and 56470 people with 8684 image links.
[9/16/2021 4:30:47 AM] [ INFO] Completed EPG123 update execution. SUCCESS.
[9/16/2021 4:30:47 AM] [ INFO] epg123 update execution time was 00:00:47.4500665.
[9/16/2021 4:30:48 AM] ===============================================================================
[9/16/2021 4:30:48 AM]  Beginning epg123 client execution. version 1.6.7.0
[9/16/2021 4:30:48 AM] ===============================================================================
[9/16/2021 4:30:48 AM] [ INFO] Beginning epg123 client execution. 2021-09-16 08:30:48Z
[9/16/2021 4:30:48 AM] [ INFO] Import: True , Match: True , NoLogo: False , Force: False , ShowProgress: False , NoGC: False , NoVerify: False , Verbose: False
[9/16/2021 4:30:52 AM] Entering ImportMxfFile() for file "c:\epg123\output\epg123.mxf".
[9/16/2021 4:34:10 AM] [ INFO] Successfully imported .mxf file into Media Center database. Exit code: 0
[9/16/2021 4:34:10 AM] Exiting ImportMxfFile(). SUCCESS.
[9/16/2021 4:34:10 AM] Entering VerifyLoad()
[9/16/2021 4:34:11 AM] [ INFO] Service WRICDT has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:15 AM] [ INFO] Service WRIC has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:18 AM] [ INFO] Service OWN has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:20 AM] [ INFO] Service MTV has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:21 AM] [ INFO] Service WE has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:24 AM] [ INFO] Service BBCWDNA has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:28 AM] [ INFO] Service CBSSN has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:30 AM] [ INFO] Service WEHD has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:32 AM] [ INFO] Service CBSSNHD has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:34 AM] [ INFO] Service MTVHD has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:35 AM] [ INFO] Service OWNHD has a time discontinuity. Skipping verification of this station's schedule entries.
[9/16/2021 4:34:38 AM] [ INFO] Checked 127635 entries and corrected 84 of them.
[9/16/2021 4:34:38 AM] Exiting VerifyLoad()
[9/16/2021 4:34:40 AM] [ INFO] Completed the automatch of lineup stations to tuner channels.
[9/16/2021 4:34:41 AM] [ INFO] Completed lineup refresh.
[9/16/2021 4:34:41 AM] Entering RunWmcIndexTask(PvrScheduleTask)
[9/16/2021 4:34:41 AM] [ INFO] SUCCESS: Attempted to run the scheduled task "Microsoft\Windows\Media Center\PvrScheduleTask".
[9/16/2021 4:34:41 AM] [ INFO] Successfully started the PvrScheduleTask task. Exit code: 0
[9/16/2021 4:34:41 AM] Exiting RunWmcIndexTask(PvrScheduleTask). SUCCESS.
[9/16/2021 4:34:41 AM] Entering RunWmcIndexTask(ReindexSearchRoot)
[9/16/2021 4:34:42 AM] [ INFO] SUCCESS: Attempted to run the scheduled task "Microsoft\Windows\Media Center\ReindexSearchRoot".
[9/16/2021 4:34:42 AM] [ INFO] Successfully started the ReindexSearchRoot task. Exit code: 0
[9/16/2021 4:34:42 AM] Exiting RunWmcIndexTask(ReindexSearchRoot). SUCCESS.
[9/16/2021 4:34:42 AM] [ INFO] MXF file was created on 9/16/2021 4:30:00 AM
[9/16/2021 4:34:42 AM] [ INFO] Completed EPG123 client execution.
[9/16/2021 4:34:48 AM] [ INFO] EPG123 client execution time was 00:04:00.3503365.
[9/16/2021 12:43:57 PM] ===============================================================================
[9/16/2021 12:43:57 PM]  Activating the epg123 client GUI. version 1.7.0.2
[9/16/2021 12:43:57 PM] ===============================================================================
[9/16/2021 12:43:57 PM] [ INFO] Successfully queried the Task Scheduler for status. Ready. Last Run 9/16/2021 4:30:00 AM; Exit: 0x00000000
[9/16/2021 12:44:18 PM] ===============================================================================
[9/16/2021 12:44:18 PM]  Activating the epg123 client GUI. version 1.7.0.2
[9/16/2021 12:44:18 PM] ===============================================================================
[9/16/2021 12:44:19 PM] [ INFO] Successfully queried the Task Scheduler for status. Ready. Last Run 9/16/2021 4:30:00 AM; Exit: 0x00000000
[9/16/2021 12:45:20 PM] ===============================================================================
[9/16/2021 12:45:20 PM]  Activating the epg123 configuration GUI. version 1.7.0.2
[9/16/2021 12:45:20 PM] ===============================================================================
[9/16/2021 12:45:21 PM] [ INFO] GetClientVersion() Unknown exception thrown. Message: The operation has timed out
[9/16/2021 12:45:22 PM] [ INFO] Token request successful. serverID: 20141201.web.1 , datetime: 2021-09-16T16:45:21Z
[9/16/2021 12:45:23 PM] [ INFO] Status request successful. account expires: 2022-02-11T18:22:35Z , lineups: 2/4 , lastDataUpdate: 2021-09-15T22:14:00Z
[9/16/2021 12:45:23 PM] [ INFO] system status: Online , message: No known issues.
[9/16/2021 12:45:23 PM] [ INFO] Successfully requested listing of subscribed lineups from Schedules Direct.
[9/16/2021 12:45:23 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-OTA-23233.
[9/16/2021 12:45:23 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-VA45521-X.
[9/16/2021 12:45:23 PM] [ INFO] Successfully queried the Task Scheduler for status. Ready. Last Run 9/16/2021 4:30:00 AM; Exit: 0x00000000
[9/16/2021 12:45:49 PM] ===============================================================================
[9/16/2021 12:45:49 PM]  Beginning epg123 update execution. version 1.7.0.2
[9/16/2021 12:45:49 PM] ===============================================================================
[9/16/2021 12:45:49 PM] [ INFO] Beginning EPG123 update execution. 2021-09-16 16:45:49Z
[9/16/2021 12:45:49 PM] [ INFO] DaysToDownload: 14 , TheTVDBNumbers : False , PrefixEpisodeTitle: True , PrefixEpisodeDescription : True , AppendEpisodeDesc: True , OADOverride : True , TMDbCoverArt: True , IncludeSDLogos : False , AutoAddNew: True , CreateXmltv: False , ModernMediaUiPlusSupport: False
[9/16/2021 12:45:49 PM] [ INFO] Status request successful. account expires: 2022-02-11T18:22:35Z , lineups: 2/4 , lastDataUpdate: 2021-09-15T22:14:00Z
[9/16/2021 12:45:49 PM] [ INFO] system status: Online , message: No known issues.
[9/16/2021 12:45:52 PM] [ INFO] Successfully retrieved TMDb configurations.
[9/16/2021 12:45:52 PM] [ INFO] Successfully requested listing of subscribed lineups from Schedules Direct.
[9/16/2021 12:45:52 PM] Entering BuildLineupServices() for 2 lineups.
[9/16/2021 12:45:52 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-OTA-23233.
[9/16/2021 12:45:52 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-VA45521-X.
[9/16/2021 12:45:52 PM] Exiting BuildLineupServices(). SUCCESS.
[9/16/2021 12:45:52 PM] Entering GetAllScheduleEntryMd5s() for 14 days on 380 stations.
[9/16/2021 12:45:56 PM] [ INFO] Successfully retrieved Md5s for 357 station's daily schedules. (0:00:00:03.7500052 /   532.240 KB)
[9/16/2021 12:45:56 PM] [ INFO] Successfully retrieved Md5s for  23 station's daily schedules. (0:00:00:00.3700005 /    34.305 KB)
[9/16/2021 12:45:56 PM] [ INFO] Found 5320 cached daily schedules.
[9/16/2021 12:45:57 PM] [ INFO] Downloaded 0 daily schedules.
[9/16/2021 12:45:59 PM] [ INFO] Processed 5320 daily schedules for 380 stations.
[9/16/2021 12:45:59 PM] Exiting GetAllScheduleEntryMd5s(). SUCCESS.
[9/16/2021 12:45:59 PM] Entering BuildAllProgramEntries() for 36115 programs.
[9/16/2021 12:46:06 PM] [ INFO] Found 36115 cached program descriptions.
[9/16/2021 12:46:06 PM] [ INFO] Processed 36115 program descriptions.
[9/16/2021 12:46:06 PM] Exiting BuildAllProgramEntries(). SUCCESS.
[9/16/2021 12:46:06 PM] Entering BuildAllGenericSeriesInfoDescriptions() for 4733 series.
[9/16/2021 12:46:06 PM] [ INFO] Found 4732 cached/unavailable series descriptions.
[9/16/2021 12:46:06 PM] [ INFO] Successfully retrieved   1 generic program descriptions. (0:00:00:00.0700001 /     3.000 B)
[9/16/2021 12:46:06 PM] [ INFO] GetGenericDescriptions() Unknown exception thrown. Message: Cannot deserialize the current JSON array (e.g. [1,2,3]) into type 'System.Collections.Generic.Dictionary`2[System.String,epg123.SchedulesDirect.GenericDescription]' because the type requires a JSON object (e.g. {"name":"value"}) to deserialize correctly.
To fix this error either change the JSON to a JSON object (e.g. {"name":"value"}) or change the deserialized type to an array or a type that implements a collection interface (e.g. ICollection, IList) like List<T> that can be deserialized from a JSON array. JsonArrayAttribute can also be added to the type to force it to deserialize from a JSON array.
Path '', line 1, position 1.
[9/16/2021 12:46:06 PM] [ INFO] Problem occurred during BuildGenericSeriesInfoDescriptions(). Did not process all series descriptions.
[9/16/2021 12:46:06 PM] [ INFO] Processed 4732 series descriptions.
[9/16/2021 12:46:06 PM] Exiting BuildAllGenericSeriesInfoDescriptions(). SUCCESS.
[9/16/2021 12:46:06 PM] Entering GetAllMoviePosters() for 2777 movies.
[9/16/2021 12:46:06 PM] [ INFO] Found 2777 cached/unavailable movie poster links.
[9/16/2021 12:46:06 PM] [ INFO] Processed 2777 movie poster links.
[9/16/2021 12:46:06 PM] Exiting GetAllMoviePosters(). SUCCESS.
[9/16/2021 12:46:06 PM] Entering GetAllSeriesImages() for 4733 series.
[9/16/2021 12:46:06 PM] [ INFO] Found 4588 cached/unavailable series image links.
[9/16/2021 12:46:07 PM] [ INFO] Successfully retrieved artwork info for 145 programs. (0:00:00:00.3700005 /    77.776 KB)
[9/16/2021 12:46:07 PM] [ INFO] Processed 4733 series image links.
[9/16/2021 12:46:07 PM] Exiting GetAllSeriesImages(). SUCCESS.
[9/16/2021 12:46:07 PM] Entering GetAllSeasonImages() for 4479 seasons.
[9/16/2021 12:46:07 PM] [ INFO] Found 4405 cached/unavailable season image links.
[9/16/2021 12:46:07 PM] [ INFO] Successfully retrieved artwork info for  74 programs. (0:00:00:00.2400004 /   429.521 KB)
[9/16/2021 12:46:07 PM] [ INFO] Processed 4479 season image links.
[9/16/2021 12:46:07 PM] Exiting GetAllSeasonImages(). SUCCESS.
[9/16/2021 12:46:07 PM] Entering GetAllSportsImages() for 0 sports events.
[9/16/2021 12:46:07 PM] [ INFO] Found 0 cached/unavailable sport event image links.
[9/16/2021 12:46:07 PM] [ INFO] Processed 0 sport event image links.
[9/16/2021 12:46:07 PM] Exiting GetAllSportsImages(). SUCCESS.
[9/16/2021 12:46:07 PM] [ INFO] Completed compiling keywords and keyword groups.
[9/16/2021 12:46:10 PM] [ INFO] Completed save of the MXF file to "C:\epg123\output\epg123.mxf".
[9/16/2021 12:46:10 PM] [ INFO] 26 entries deleted from the cache file during cleanup.
[9/16/2021 12:46:11 PM] [ INFO] Downloaded and processed     1.049 MB of data from Schedules Direct.
[9/16/2021 12:46:11 PM] [ INFO] Generated .mxf file contains 380 services, 4733 series, 4479 seasons, 36115 programs, 132059 schedule entries, and 56470 people with 8684 image links.
[9/16/2021 12:46:11 PM] [ INFO] Completed EPG123 update execution. SUCCESS.
[9/16/2021 12:46:11 PM] [ INFO] epg123 update execution time was 00:00:22.6800318.
[9/16/2021 12:46:12 PM] ===============================================================================
[9/16/2021 12:46:12 PM]  Beginning epg123 client execution. version 1.7.0.2
[9/16/2021 12:46:12 PM] ===============================================================================
[9/16/2021 12:46:12 PM] [ INFO] Beginning epg123 client execution. 2021-09-16 16:46:12Z
[9/16/2021 12:46:12 PM] [ INFO] Import: True , Match: True , NoLogo: False , Force: False , ShowProgress: True , NoGC: True , NoVerify: True , Verbose: False
[9/16/2021 12:46:15 PM] Entering ImportMxfFile() for file "c:\epg123\output\epg123.mxf".
[9/16/2021 12:52:32 PM] ===============================================================================
[9/16/2021 12:52:32 PM] [ERROR] An instance of epg123 is already running. Aborting.
[9/16/2021 12:52:32 PM] ===============================================================================
[9/16/2021 12:54:00 PM] [ INFO] Unhandled Exception: System.ObjectDisposedException: Cannot access a disposed object.
[9/16/2021 12:54:00 PM] [ INFO] Object name: 'ObjectStoreEvents is null'.
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Store.CrashProtector.ThrowObjectDisposed(String objectName)
[9/16/2021 12:54:00 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsEnumerator..ctor(ObjectStore store, WhereClause whereClause, ObjectType typeElem, ObjectType returnType, UpdateMode updateMode)
[9/16/2021 12:54:00 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsEnumerator..ctor(ObjectStore store, WhereClause whereClause, ObjectType typeElem)
[9/16/2021 12:54:00 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsRecord.NewEnumerator()
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Store.StoredObjects.GetStoredObjectsEnumerator[T]()
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Store.StoredObjects`1.System.Collections.Generic.IEnumerable<T>.GetEnumerator()
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.SchedulerCache.GetAssignmentGroups(RequestedProgram requestedProgram)
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.BranchAndBoundSchedulingStep.TryNext(PriorityListBase priorityList, PriorityQueue`1 queue, RequestedPrograms allRequestedPrograms, Int32 maxScheduleCount, Scheduler scheduler, BranchAndBoundSchedulingStep& next)
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.BranchAndBoundSchedulingAlgorithm(ScheduleBase schedule, PriorityListBase priorityList, IEnumerable`1 requestedPrograms, Int32 maxScheduleCount, ExpressionLockHandle`1 lockHandle)
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.<CreateSchedule>b__8(ScheduleParameters parameters, ExpressionLockHandle`1 lockHandle)
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.OptimisticReader`3.Read(ReaderAction2 readerAction, P parameter, Expression expression)
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.CreateSchedule(ScheduleBase schedule, PriorityListBase priorityList, IEnumerable`1 requestedPrograms)
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.Schedule(IEnumerable`1 requestedPrograms)
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.Schedule()
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Requests.UpdateAllActive(ObjectStore objectStore)
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.FullRescheduleWorker.Work()
[9/16/2021 12:54:00 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.SignaledWorker.ThreadProcess()
[9/16/2021 12:54:00 PM] [ INFO]    at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state)
[9/16/2021 12:54:00 PM] [ INFO]    at System.Threading.ThreadHelper.ThreadStart()
[9/16/2021 12:54:00 PM] [ERROR] Error using loadmxf.exe to import new guide information. Exit code: -532459699
[9/16/2021 12:54:00 PM] Exiting ImportMxfFile(). FAILURE.
[9/16/2021 12:54:00 PM] [ERROR] Failed to import .mxf file. Exiting.
[9/16/2021 12:54:00 PM] [ INFO] MXF file was created on 9/16/2021 12:45:49 PM
[9/16/2021 12:54:00 PM] [ INFO] Completed EPG123 client execution.
[9/16/2021 12:54:00 PM] [ INFO] EPG123 client execution time was 00:07:48.9134751.
[9/16/2021 1:02:36 PM] ===============================================================================
[9/16/2021 1:02:36 PM]  Beginning epg123 update execution. version 1.7.0.2
[9/16/2021 1:02:36 PM] ===============================================================================
[9/16/2021 1:02:36 PM] [ INFO] Beginning EPG123 update execution. 2021-09-16 17:02:36Z
[9/16/2021 1:02:36 PM] [ INFO] DaysToDownload: 14 , TheTVDBNumbers : False , PrefixEpisodeTitle: True , PrefixEpisodeDescription : True , AppendEpisodeDesc: True , OADOverride : True , TMDbCoverArt: True , IncludeSDLogos : False , AutoAddNew: True , CreateXmltv: False , ModernMediaUiPlusSupport: False
[9/16/2021 1:02:36 PM] [ INFO] Status request successful. account expires: 2022-02-11T18:22:35Z , lineups: 2/4 , lastDataUpdate: 2021-09-15T22:14:00Z
[9/16/2021 1:02:36 PM] [ INFO] system status: Online , message: No known issues.
[9/16/2021 1:02:37 PM] [ INFO] GetClientVersion() Unknown exception thrown. Message: The operation has timed out
[9/16/2021 1:02:39 PM] [ INFO] Successfully retrieved TMDb configurations.
[9/16/2021 1:02:39 PM] [ INFO] Successfully requested listing of subscribed lineups from Schedules Direct.
[9/16/2021 1:02:39 PM] Entering BuildLineupServices() for 2 lineups.
[9/16/2021 1:02:39 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-OTA-23233.
[9/16/2021 1:02:39 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-VA45521-X.
[9/16/2021 1:02:39 PM] Exiting BuildLineupServices(). SUCCESS.
[9/16/2021 1:02:39 PM] Entering GetAllScheduleEntryMd5s() for 14 days on 380 stations.
[9/16/2021 1:02:44 PM] [ INFO] Successfully retrieved Md5s for 357 station's daily schedules. (0:00:00:04.2300059 /   532.240 KB)
[9/16/2021 1:02:44 PM] [ INFO] Successfully retrieved Md5s for  23 station's daily schedules. (0:00:00:00.3900005 /    34.305 KB)
[9/16/2021 1:02:44 PM] [ INFO] Found 5320 cached daily schedules.
[9/16/2021 1:02:44 PM] [ INFO] Downloaded 0 daily schedules.
[9/16/2021 1:02:47 PM] [ INFO] Processed 5320 daily schedules for 380 stations.
[9/16/2021 1:02:47 PM] Exiting GetAllScheduleEntryMd5s(). SUCCESS.
[9/16/2021 1:02:47 PM] Entering BuildAllProgramEntries() for 36115 programs.
[9/16/2021 1:02:52 PM] [ INFO] Found 36115 cached program descriptions.
[9/16/2021 1:02:52 PM] [ INFO] Processed 36115 program descriptions.
[9/16/2021 1:02:52 PM] Exiting BuildAllProgramEntries(). SUCCESS.
[9/16/2021 1:02:52 PM] Entering BuildAllGenericSeriesInfoDescriptions() for 4733 series.
[9/16/2021 1:02:52 PM] [ INFO] Found 4732 cached/unavailable series descriptions.
[9/16/2021 1:02:52 PM] [ INFO] Successfully retrieved   1 generic program descriptions. (0:00:00:00.0600001 /     3.000 B)
[9/16/2021 1:02:52 PM] [ INFO] GetGenericDescriptions() Unknown exception thrown. Message: Cannot deserialize the current JSON array (e.g. [1,2,3]) into type 'System.Collections.Generic.Dictionary`2[System.String,epg123.SchedulesDirect.GenericDescription]' because the type requires a JSON object (e.g. {"name":"value"}) to deserialize correctly.
To fix this error either change the JSON to a JSON object (e.g. {"name":"value"}) or change the deserialized type to an array or a type that implements a collection interface (e.g. ICollection, IList) like List<T> that can be deserialized from a JSON array. JsonArrayAttribute can also be added to the type to force it to deserialize from a JSON array.
Path '', line 1, position 1.
[9/16/2021 1:02:52 PM] [ INFO] Problem occurred during BuildGenericSeriesInfoDescriptions(). Did not process all series descriptions.
[9/16/2021 1:02:52 PM] [ INFO] Processed 4732 series descriptions.
[9/16/2021 1:02:52 PM] Exiting BuildAllGenericSeriesInfoDescriptions(). SUCCESS.
[9/16/2021 1:02:52 PM] Entering GetAllMoviePosters() for 2777 movies.
[9/16/2021 1:02:52 PM] [ INFO] Found 2777 cached/unavailable movie poster links.
[9/16/2021 1:02:52 PM] [ INFO] Processed 2777 movie poster links.
[9/16/2021 1:02:52 PM] Exiting GetAllMoviePosters(). SUCCESS.
[9/16/2021 1:02:52 PM] Entering GetAllSeriesImages() for 4733 series.
[9/16/2021 1:02:52 PM] [ INFO] Found 4588 cached/unavailable series image links.
[9/16/2021 1:02:53 PM] [ INFO] Successfully retrieved artwork info for 145 programs. (0:00:00:00.3400005 /    77.776 KB)
[9/16/2021 1:02:53 PM] [ INFO] Processed 4733 series image links.
[9/16/2021 1:02:53 PM] Exiting GetAllSeriesImages(). SUCCESS.
[9/16/2021 1:02:53 PM] Entering GetAllSeasonImages() for 4479 seasons.
[9/16/2021 1:02:53 PM] [ INFO] Found 4405 cached/unavailable season image links.
[9/16/2021 1:02:53 PM] [ INFO] Successfully retrieved artwork info for  74 programs. (0:00:00:00.3300005 /   429.521 KB)
[9/16/2021 1:02:53 PM] [ INFO] Processed 4479 season image links.
[9/16/2021 1:02:53 PM] Exiting GetAllSeasonImages(). SUCCESS.
[9/16/2021 1:02:53 PM] Entering GetAllSportsImages() for 0 sports events.
[9/16/2021 1:02:53 PM] [ INFO] Found 0 cached/unavailable sport event image links.
[9/16/2021 1:02:53 PM] [ INFO] Processed 0 sport event image links.
[9/16/2021 1:02:53 PM] Exiting GetAllSportsImages(). SUCCESS.
[9/16/2021 1:02:53 PM] [ INFO] Completed compiling keywords and keyword groups.
[9/16/2021 1:02:56 PM] [ INFO] Completed save of the MXF file to "C:\epg123\output\epg123.mxf".
[9/16/2021 1:02:56 PM] [ INFO] 48 entries deleted from the cache file during cleanup.
[9/16/2021 1:02:57 PM] [ INFO] Downloaded and processed     1.049 MB of data from Schedules Direct.
[9/16/2021 1:02:57 PM] [ INFO] Generated .mxf file contains 380 services, 4733 series, 4479 seasons, 36115 programs, 132059 schedule entries, and 56470 people with 8684 image links.
[9/16/2021 1:02:57 PM] [ INFO] Completed EPG123 update execution. SUCCESS.
[9/16/2021 1:02:58 PM] [ INFO] epg123 update execution time was 00:00:21.9500308.
[9/16/2021 1:02:58 PM] ===============================================================================
[9/16/2021 1:02:58 PM]  Beginning epg123 client execution. version 1.7.0.2
[9/16/2021 1:02:58 PM] ===============================================================================
[9/16/2021 1:02:58 PM] [ INFO] Beginning epg123 client execution. 2021-09-16 17:02:58Z
[9/16/2021 1:02:58 PM] [ INFO] Import: True , Match: True , NoLogo: False , Force: False , ShowProgress: False , NoGC: True , NoVerify: False , Verbose: False
[9/16/2021 1:03:02 PM] Entering ImportMxfFile() for file "c:\epg123\output\epg123.mxf".
[9/16/2021 1:10:55 PM] [ INFO] Unhandled Exception: System.ObjectDisposedException: Cannot access a disposed object.
[9/16/2021 1:10:55 PM] [ INFO] Object name: 'ObjectStoreEvents is null'.
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Store.CrashProtector.ThrowObjectDisposed(String objectName)
[9/16/2021 1:10:55 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsEnumerator..ctor(ObjectStore store, WhereClause whereClause, ObjectType typeElem, ObjectType returnType, UpdateMode updateMode)
[9/16/2021 1:10:55 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsEnumerator..ctor(ObjectStore store, WhereClause whereClause, ObjectType typeElem)
[9/16/2021 1:10:55 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsRecord.NewEnumerator()
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Store.StoredObjects.GetStoredObjectsEnumerator[T]()
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Store.StoredObjects`1.System.Collections.Generic.IEnumerable<T>.GetEnumerator()
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.SchedulerCache.GetAssignmentGroups(RequestedProgram requestedProgram)
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.BranchAndBoundSchedulingStep.TryNext(PriorityListBase priorityList, PriorityQueue`1 queue, RequestedPrograms allRequestedPrograms, Int32 maxScheduleCount, Scheduler scheduler, BranchAndBoundSchedulingStep& next)
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.BranchAndBoundSchedulingAlgorithm(ScheduleBase schedule, PriorityListBase priorityList, IEnumerable`1 requestedPrograms, Int32 maxScheduleCount, ExpressionLockHandle`1 lockHandle)
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.<CreateSchedule>b__8(ScheduleParameters parameters, ExpressionLockHandle`1 lockHandle)
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.OptimisticReader`3.Read(ReaderAction2 readerAction, P parameter, Expression expression)
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.CreateSchedule(ScheduleBase schedule, PriorityListBase priorityList, IEnumerable`1 requestedPrograms)
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.Schedule(IEnumerable`1 requestedPrograms)
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.Schedule()
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Requests.UpdateAllActive(ObjectStore objectStore)
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.FullRescheduleWorker.Work()
[9/16/2021 1:10:55 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.SignaledWorker.ThreadProcess()
[9/16/2021 1:10:55 PM] [ INFO]    at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state)
[9/16/2021 1:10:55 PM] [ INFO]    at System.Threading.ThreadHelper.ThreadStart()
[9/16/2021 1:10:55 PM] [ERROR] Error using loadmxf.exe to import new guide information. Exit code: -532459699
[9/16/2021 1:10:55 PM] Exiting ImportMxfFile(). FAILURE.
[9/16/2021 1:10:55 PM] [ERROR] Failed to import .mxf file. Exiting.
[9/16/2021 1:10:56 PM] [ INFO] MXF file was created on 9/16/2021 1:02:36 PM
[9/16/2021 1:10:56 PM] [ INFO] Completed EPG123 client execution.
[9/16/2021 1:10:57 PM] [ INFO] EPG123 client execution time was 00:07:58.4847024.
[9/16/2021 1:47:00 PM] ===============================================================================
[9/16/2021 1:47:00 PM]  Activating the epg123 client GUI. version 1.7.0.2
[9/16/2021 1:47:00 PM] ===============================================================================
[9/16/2021 1:47:00 PM] [ INFO] Successfully queried the Task Scheduler for status. Ready. Last Run 9/16/2021 1:02:35 PM; Exit: 0x00000000
[9/16/2021 1:47:45 PM] ===============================================================================
[9/16/2021 1:47:45 PM]  Beginning epg123 update execution. version 1.7.0.2
[9/16/2021 1:47:45 PM] ===============================================================================
[9/16/2021 1:47:45 PM] [ INFO] Beginning EPG123 update execution. 2021-09-16 17:47:45Z
[9/16/2021 1:47:45 PM] [ INFO] DaysToDownload: 14 , TheTVDBNumbers : False , PrefixEpisodeTitle: True , PrefixEpisodeDescription : True , AppendEpisodeDesc: True , OADOverride : True , TMDbCoverArt: True , IncludeSDLogos : False , AutoAddNew: True , CreateXmltv: False , ModernMediaUiPlusSupport: False
[9/16/2021 1:47:47 PM] [ INFO] Status request successful. account expires: 2022-02-11T18:22:35Z , lineups: 2/4 , lastDataUpdate: 2021-09-16T17:12:15Z
[9/16/2021 1:47:47 PM] [ INFO] system status: Online , message: No known issues.
[9/16/2021 1:47:49 PM] [ INFO] GetClientVersion() Unknown exception thrown. Message: The operation has timed out
[9/16/2021 1:47:52 PM] [ INFO] Successfully retrieved TMDb configurations.
[9/16/2021 1:47:52 PM] [ INFO] Successfully requested listing of subscribed lineups from Schedules Direct.
[9/16/2021 1:47:52 PM] Entering BuildLineupServices() for 2 lineups.
[9/16/2021 1:47:53 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-OTA-23233.
[9/16/2021 1:47:53 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-VA45521-X.
[9/16/2021 1:47:53 PM] Exiting BuildLineupServices(). SUCCESS.
[9/16/2021 1:47:53 PM] Entering GetAllScheduleEntryMd5s() for 14 days on 380 stations.
[9/16/2021 1:47:56 PM] [ INFO] Successfully retrieved Md5s for 357 station's daily schedules. (0:00:00:02.8500040 /   532.264 KB)
[9/16/2021 1:47:56 PM] [WARNG] Duplicate schedule Md5 return for stationId 58649 (FBN) on 2021-09-29 with 2021-09-22.
[9/16/2021 1:47:56 PM] [WARNG] Removing duplicate Md5 schedule entry for stationId 58649 (FBN) on 2021-09-22.
[9/16/2021 1:47:56 PM] [WARNG] Duplicate schedule Md5 return for stationId 58718 (FBNHD) on 2021-09-29 with 2021-09-22.
[9/16/2021 1:47:56 PM] [WARNG] Removing duplicate Md5 schedule entry for stationId 58718 (FBNHD) on 2021-09-22.
[9/16/2021 1:48:00 PM] [ INFO] Successfully retrieved 225 station's daily schedules.          (0:00:00:04.3700061 /     6.211 MB)
[9/16/2021 1:48:02 PM] [WARNG] Md5 mismatch for station 58649 on 2021-09-22. Downloaded y1RWbFwmPyLWqjaPOEFWQg
[9/16/2021 1:48:02 PM] [WARNG] Md5 mismatch for station 58649 on 2021-09-29. Downloaded y1RWbFwmPyLWqjaPOEFWQg
[9/16/2021 1:48:03 PM] [WARNG] Md5 mismatch for station 58718 on 2021-09-22. Downloaded gjdP4Pps0Yx+nS0SnjfljQ
[9/16/2021 1:48:03 PM] [WARNG] Md5 mismatch for station 58718 on 2021-09-29. Downloaded gjdP4Pps0Yx+nS0SnjfljQ
[9/16/2021 1:48:05 PM] [ INFO] Successfully retrieved Md5s for  23 station's daily schedules. (0:00:00:00.3500005 /    34.302 KB)
[9/16/2021 1:48:06 PM] [ INFO] Successfully retrieved  13 station's daily schedules.          (0:00:00:00.4600006 /   464.055 KB)
[9/16/2021 1:48:06 PM] [ INFO] Found 4222 cached daily schedules.
[9/16/2021 1:48:06 PM] [ INFO] Downloaded 1098 daily schedules.
[9/16/2021 1:48:09 PM] [ INFO] Processed 5316 daily schedules for 380 stations.
[9/16/2021 1:48:09 PM] Exiting GetAllScheduleEntryMd5s(). SUCCESS.
[9/16/2021 1:48:09 PM] Entering BuildAllProgramEntries() for 36535 programs.
[9/16/2021 1:48:15 PM] [ INFO] Found 35588 cached program descriptions.
[9/16/2021 1:48:19 PM] [ INFO] Successfully retrieved  947 program descriptions. (0:00:00:04.4800063 /     1.841 MB)
[9/16/2021 1:48:20 PM] [ INFO] Processed 36535 program descriptions.
[9/16/2021 1:48:20 PM] Exiting BuildAllProgramEntries(). SUCCESS.
[9/16/2021 1:48:20 PM] Entering BuildAllGenericSeriesInfoDescriptions() for 4761 series.
[9/16/2021 1:48:20 PM] [ INFO] Found 4741 cached/unavailable series descriptions.
[9/16/2021 1:48:20 PM] [ INFO] Successfully retrieved  20 generic program descriptions. (0:00:00:00.2800004 /     5.668 KB)
[9/16/2021 1:48:20 PM] [ INFO] Problem occurred during BuildGenericSeriesInfoDescriptions(). Did not process all series descriptions.
[9/16/2021 1:48:20 PM] [ INFO] Processed 4760 series descriptions.
[9/16/2021 1:48:20 PM] Exiting BuildAllGenericSeriesInfoDescriptions(). SUCCESS.
[9/16/2021 1:48:20 PM] Entering GetAllMoviePosters() for 2783 movies.
[9/16/2021 1:48:20 PM] [ INFO] Found 2740 cached/unavailable movie poster links.
[9/16/2021 1:48:21 PM] [ INFO] Successfully retrieved artwork info for  43 programs. (0:00:00:00.3000004 /   605.621 KB)
[9/16/2021 1:48:21 PM] [ INFO] TMDb catalog search for "Neh" from 2021 found 1 results.
[9/16/2021 1:48:21 PM] [ INFO] TMDb catalog search for "Little Sky" from 2021 found 1 results.
[9/16/2021 1:48:21 PM] [ INFO] TMDb catalog search for "Free Fall" from 1999 found 1 results.
[9/16/2021 1:48:21 PM] [ INFO] Processed 2783 movie poster links.
[9/16/2021 1:48:21 PM] Exiting GetAllMoviePosters(). SUCCESS.
[9/16/2021 1:48:21 PM] Entering GetAllSeriesImages() for 4761 series.
[9/16/2021 1:48:21 PM] [ INFO] Found 4563 cached/unavailable series image links.
[9/16/2021 1:48:22 PM] [ INFO] Successfully retrieved artwork info for 198 programs. (0:00:00:00.5600008 /   399.433 KB)
[9/16/2021 1:48:22 PM] [ INFO] Processed 4761 series image links.
[9/16/2021 1:48:22 PM] Exiting GetAllSeriesImages(). SUCCESS.
[9/16/2021 1:48:22 PM] Entering GetAllSeasonImages() for 4500 seasons.
[9/16/2021 1:48:22 PM] [ INFO] Found 4419 cached/unavailable season image links.
[9/16/2021 1:48:22 PM] [ INFO] Successfully retrieved artwork info for  81 programs. (0:00:00:00.3300004 /   512.028 KB)
[9/16/2021 1:48:22 PM] [ INFO] Processed 4500 season image links.
[9/16/2021 1:48:22 PM] Exiting GetAllSeasonImages(). SUCCESS.
[9/16/2021 1:48:22 PM] Entering GetAllSportsImages() for 0 sports events.
[9/16/2021 1:48:22 PM] [ INFO] Found 0 cached/unavailable sport event image links.
[9/16/2021 1:48:22 PM] [ INFO] Processed 0 sport event image links.
[9/16/2021 1:48:22 PM] Exiting GetAllSportsImages(). SUCCESS.
[9/16/2021 1:48:23 PM] [ INFO] Completed compiling keywords and keyword groups.
[9/16/2021 1:48:25 PM] [ INFO] Completed save of the MXF file to "C:\epg123\output\epg123.mxf".
[9/16/2021 1:48:25 PM] [ INFO] 1686 entries deleted from the cache file during cleanup.
[9/16/2021 1:48:27 PM] [ INFO] Downloaded and processed    10.546 MB of data from Schedules Direct.
[9/16/2021 1:48:27 PM] [ INFO] Generated .mxf file contains 380 services, 4761 series, 4500 seasons, 36535 programs, 131834 schedule entries, and 56746 people with 8704 image links.
[9/16/2021 1:48:27 PM] [ INFO] Completed EPG123 update execution. SUCCESS.
[9/16/2021 1:48:27 PM] [ INFO] epg123 update execution time was 00:00:42.1480728.
[9/16/2021 1:48:27 PM] ===============================================================================
[9/16/2021 1:48:27 PM]  Beginning epg123 client execution. version 1.7.0.2
[9/16/2021 1:48:27 PM] ===============================================================================
[9/16/2021 1:48:27 PM] [ INFO] Beginning epg123 client execution. 2021-09-16 17:48:27Z
[9/16/2021 1:48:27 PM] [ INFO] Import: True , Match: True , NoLogo: False , Force: False , ShowProgress: False , NoGC: True , NoVerify: False , Verbose: False
[9/16/2021 1:48:32 PM] Entering ImportMxfFile() for file "c:\epg123\output\epg123.mxf".
[9/16/2021 1:57:56 PM] [ INFO] Unhandled Exception: System.ObjectDisposedException: Cannot access a disposed object.
[9/16/2021 1:57:56 PM] [ INFO] Object name: 'ObjectStoreEvents is null'.
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Store.CrashProtector.ThrowObjectDisposed(String objectName)
[9/16/2021 1:57:56 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsEnumerator..ctor(ObjectStore store, WhereClause whereClause, ObjectType typeElem, ObjectType returnType, UpdateMode updateMode)
[9/16/2021 1:57:56 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsEnumerator..ctor(ObjectStore store, WhereClause whereClause, ObjectType typeElem)
[9/16/2021 1:57:56 PM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsRecord.NewEnumerator()
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Store.StoredObjects.GetStoredObjectsEnumerator[T]()
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Store.StoredObjects`1.System.Collections.Generic.IEnumerable<T>.GetEnumerator()
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.SchedulerCache.GetAssignmentGroups(RequestedProgram requestedProgram)
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.BranchAndBoundSchedulingStep.TryNext(PriorityListBase priorityList, PriorityQueue`1 queue, RequestedPrograms allRequestedPrograms, Int32 maxScheduleCount, Scheduler scheduler, BranchAndBoundSchedulingStep& next)
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.BranchAndBoundSchedulingAlgorithm(ScheduleBase schedule, PriorityListBase priorityList, IEnumerable`1 requestedPrograms, Int32 maxScheduleCount, ExpressionLockHandle`1 lockHandle)
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.<CreateSchedule>b__8(ScheduleParameters parameters, ExpressionLockHandle`1 lockHandle)
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.OptimisticReader`3.Read(ReaderAction2 readerAction, P parameter, Expression expression)
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.CreateSchedule(ScheduleBase schedule, PriorityListBase priorityList, IEnumerable`1 requestedPrograms)
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.Schedule(IEnumerable`1 requestedPrograms)
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.Schedule()
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.Requests.UpdateAllActive(ObjectStore objectStore)
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.FullRescheduleWorker.Work()
[9/16/2021 1:57:56 PM] [ INFO]    at Microsoft.MediaCenter.Pvr.SignaledWorker.ThreadProcess()
[9/16/2021 1:57:56 PM] [ INFO]    at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state)
[9/16/2021 1:57:56 PM] [ INFO]    at System.Threading.ThreadHelper.ThreadStart()
[9/16/2021 1:57:56 PM] [ERROR] Error using loadmxf.exe to import new guide information. Exit code: -532459699
[9/16/2021 1:57:56 PM] Exiting ImportMxfFile(). FAILURE.
[9/16/2021 1:57:56 PM] [ERROR] Failed to import .mxf file. Exiting.
[9/16/2021 1:57:56 PM] [ INFO] MXF file was created on 9/16/2021 1:47:45 PM
[9/16/2021 1:57:56 PM] [WARNG] The imported MXF file contained a WARNING in its status field.
[9/16/2021 1:57:56 PM] [ INFO] Completed EPG123 client execution.
[9/16/2021 1:57:56 PM] [ INFO] EPG123 client execution time was 00:09:28.6711036.
Ran it from the GUI and get "Windows Media Center MXF Loader has stopped working"

Code: Select all

Description:
  Stopped working

Problem signature:
  Problem Event Name:	CLR20r3
  Problem Signature 01:	loadmxf.exe
  Problem Signature 02:	6.1.7600.16385
  Problem Signature 03:	4a5bb41e
  Problem Signature 04:	mcstore
  Problem Signature 05:	6.1.0.0
  Problem Signature 06:	4ce7b873
  Problem Signature 07:	328
  Problem Signature 08:	19
  Problem Signature 09:	System.ObjectDisposedException
  OS Version:	6.1.7601.2.1.0.256.1
  Locale ID:	1033
I am not sure what is going on with this one. LoadMXF is throwing the error which is the WMC executable that imports the MXF file. If there was a symantic error with the MXF file that epg123 creates, it would identify line and position numbers that are incorrect. Since it is throwing during a PVR phase, I don't know how to address it directly, but you have some other things going on in the log. It looks like you were trying to get updates while SD was getting updates from Gracenote and updating their database (the duplicate schedule MD5's and MD5 mismatches). I would suggest clearing your cache and trying again. If it still fails, then you may have a corrupted WMC database.
- Gary
Keeping WMC alive beyond January 2020. https://garyan2.github.io
Top
User avatar
garyan2

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

HTPC Specs: Show details

#24

Post by garyan2 » Fri Sep 17, 2021 3:22 am

garyan2 wrote: ↑Thu Sep 16, 2021 8:03 pm
IT Troll wrote: ↑Thu Sep 16, 2021 5:02 pm The registry existed and contained a token. Expiry was later tonight, ~24 hours after I upgraded to this version.
The initial problem I believe is the sequence of the installer. If the service starts prior to the registry keys existing, then no matter the settings it will not cache or auto refresh. The service would have to be stopped and restarted. I'll see what I can do with the installer for this.
Just a follow up. It wasn't the sequence of the installer but due not having a token in the registry when the service started. If that happens, then the service will assume we already tried to get a token and failed, so won't try again. That'll be fixed in next release (soon).
- Gary
Keeping WMC alive beyond January 2020. https://garyan2.github.io
Top
User avatar
IT Troll

Posts: 1193
Joined: Sun Nov 27, 2011 9:42 am
Location: Edinburgh, UK

HTPC Specs: Show details

#25

Post by IT Troll » Fri Sep 17, 2021 6:04 am

garyan2 wrote: ↑Fri Sep 17, 2021 3:22 am Just a follow up. It wasn't the sequence of the installer but due not having a token in the registry when the service started. If that happens, then the service will assume we already tried to get a token and failed, so won't try again. That'll be fixed in next release (soon).
Thanks for the follow up. Glad to hear you identified the cause. I guess this will only affect always-on machines anyway.

Thanks also for the extra detail on the IMAGE_NOT_FOUND issue, hopefully SD can correct that. I’ve not directly compared it, but the initial image request and display does feel a little slower than it did before.
Are you a Recorded TV HD user or want to give it a try? Check out the new community-made update; Recorded TV HD v2.1.1
Top
cbarbie

Posts: 25
Joined: Thu Mar 10, 2016 11:42 am
Location:

HTPC Specs: Show details

#26

Post by cbarbie » Fri Sep 17, 2021 11:11 am

garyan2 wrote: ↑Thu Sep 16, 2021 8:26 pm
cbarbie wrote: ↑Thu Sep 16, 2021 6:05 pm I updated this afternoon and ran the update 3 times. Each time the XML load errors "Unhandled Exception: System.ObjectDisposedException: Cannot access a disposed object."
Everthing ran fine with 1.6.7.0. Run log for all 4 shown below. Rebooted 3 times.

Win7 PC running WMC
I am not sure what is going on with this one. LoadMXF is throwing the error which is the WMC executable that imports the MXF file. If there was a symantic error with the MXF file that epg123 creates, it would identify line and position numbers that are incorrect. Since it is throwing during a PVR phase, I don't know how to address it directly, but you have some other things going on in the log. It looks like you were trying to get updates while SD was getting updates from Gracenote and updating their database (the duplicate schedule MD5's and MD5 mismatches). I would suggest clearing your cache and trying again. If it still fails, then you may have a corrupted WMC database.
I cleared the cache and tried again, same results MXF load failure. I wend back to 1.6.7.0 and it is working just like preupgrade to 1.7.0.2. Need any additional information or files? Log is below.

Code: Select all

[9/17/2021 4:30:00 AM] ===============================================================================
[9/17/2021 4:30:00 AM]  Beginning epg123 update execution. version 1.7.0.2
[9/17/2021 4:30:00 AM] ===============================================================================
[9/17/2021 4:30:00 AM] [ INFO] Beginning EPG123 update execution. 2021-09-17 08:30:00Z
[9/17/2021 4:30:00 AM] [ INFO] DaysToDownload: 14 , TheTVDBNumbers : False , PrefixEpisodeTitle: True , PrefixEpisodeDescription : True , AppendEpisodeDesc: True , OADOverride : True , TMDbCoverArt: True , IncludeSDLogos : False , AutoAddNew: True , CreateXmltv: False , ModernMediaUiPlusSupport: False
[9/17/2021 4:30:01 AM] [ INFO] Status request successful. account expires: 2022-02-11T18:22:35Z , lineups: 2/4 , lastDataUpdate: 2021-09-17T01:15:09Z
[9/17/2021 4:30:01 AM] [ INFO] system status: Online , message: No known issues.
[9/17/2021 4:30:02 AM] [ INFO] GetClientVersion() Unknown exception thrown. Message: The operation has timed out
[9/17/2021 4:30:05 AM] [ INFO] Successfully retrieved TMDb configurations.
[9/17/2021 4:30:05 AM] [ INFO] Successfully requested listing of subscribed lineups from Schedules Direct.
[9/17/2021 4:30:05 AM] Entering BuildLineupServices() for 2 lineups.
[9/17/2021 4:30:05 AM] [ INFO] Successfully retrieved the station mapping for lineup USA-OTA-23233.
[9/17/2021 4:30:05 AM] [ INFO] Successfully retrieved the station mapping for lineup USA-VA45521-X.
[9/17/2021 4:30:05 AM] Exiting BuildLineupServices(). SUCCESS.
[9/17/2021 4:30:05 AM] Entering GetAllScheduleEntryMd5s() for 14 days on 380 stations.
[9/17/2021 4:30:08 AM] [ INFO] Successfully retrieved Md5s for 357 station's daily schedules. (0:00:00:03.3400047 /   532.253 KB)
[9/17/2021 4:30:11 AM] [ INFO] Successfully retrieved 357 station's daily schedules.          (0:00:00:02.6000036 /     6.006 MB)
[9/17/2021 4:30:15 AM] [ INFO] Successfully retrieved Md5s for  23 station's daily schedules. (0:00:00:00.3400005 /    34.301 KB)
[9/17/2021 4:30:15 AM] [ INFO] Successfully retrieved  23 station's daily schedules.          (0:00:00:00.2900004 /   610.448 KB)
[9/17/2021 4:30:16 AM] [ INFO] Found 4150 cached daily schedules.
[9/17/2021 4:30:16 AM] [ INFO] Downloaded 1170 daily schedules.
[9/17/2021 4:30:18 AM] [ INFO] Processed 5320 daily schedules for 380 stations.
[9/17/2021 4:30:18 AM] Exiting GetAllScheduleEntryMd5s(). SUCCESS.
[9/17/2021 4:30:18 AM] Entering BuildAllProgramEntries() for 36226 programs.
[9/17/2021 4:30:23 AM] [ INFO] Found 33975 cached program descriptions.
[9/17/2021 4:30:25 AM] [ INFO] Successfully retrieved 2251 program descriptions. (0:00:00:02.1700030 /     4.039 MB)
[9/17/2021 4:30:26 AM] [ INFO] Processed 36226 program descriptions.
[9/17/2021 4:30:26 AM] Exiting BuildAllProgramEntries(). SUCCESS.
[9/17/2021 4:30:26 AM] Entering BuildAllGenericSeriesInfoDescriptions() for 4805 series.
[9/17/2021 4:30:26 AM] [ INFO] Found 4731 cached/unavailable series descriptions.
[9/17/2021 4:30:27 AM] [ INFO] Successfully retrieved  74 generic program descriptions. (0:00:00:00.5200007 /    13.457 KB)
[9/17/2021 4:30:27 AM] [ INFO] Processed 4805 series descriptions.
[9/17/2021 4:30:27 AM] Exiting BuildAllGenericSeriesInfoDescriptions(). SUCCESS.
[9/17/2021 4:30:27 AM] Entering GetAllMoviePosters() for 2763 movies.
[9/17/2021 4:30:27 AM] [ INFO] Found 2681 cached/unavailable movie poster links.
[9/17/2021 4:30:27 AM] [ INFO] Successfully retrieved artwork info for  82 programs. (0:00:00:00.3500005 /   985.168 KB)
[9/17/2021 4:30:28 AM] [ INFO] TMDb catalog search for "Was I Really Kidnapped?" from 2019 found 1 results.
[9/17/2021 4:30:28 AM] [ INFO] TMDb catalog search for "October Kiss" from 2015 found 1 results.
[9/17/2021 4:30:28 AM] [ INFO] TMDb catalog search for "Tea and Sympathy" from 1956 found 1 results.
[9/17/2021 4:30:28 AM] [ INFO] TMDb catalog search for "The Innocents" from 1961 found 1 results.
[9/17/2021 4:30:28 AM] [ INFO] TMDb catalog search for "Marriage on the Rocks" from 1965 found 1 results.
[9/17/2021 4:30:28 AM] [ INFO] TMDb catalog search for "I See a Dark Stranger" from 1946 found 1 results.
[9/17/2021 4:30:28 AM] [ INFO] TMDb catalog search for "The Hucksters" from 1947 found 1 results.
[9/17/2021 4:30:28 AM] [ INFO] TMDb catalog search for "Piccadilly" from 1929 found 1 results.
[9/17/2021 4:30:28 AM] [ INFO] TMDb catalog search for "Sparrows" from 1926 found 1 results.
[9/17/2021 4:30:28 AM] [ INFO] TMDb catalog search for "Sherlock, Jr." from 1924 found 1 results.
[9/17/2021 4:30:28 AM] [ INFO] TMDb catalog search for "The Great Buster: A Celebration" from 2018 found 1 results.
[9/17/2021 4:30:28 AM] [ INFO] TMDb catalog search for "Love's Resounding Courage" from 2011 found 1 results.
[9/17/2021 4:30:28 AM] [ INFO] TMDb catalog search for "The One I Love" from 2014 found 2 results.
[9/17/2021 4:30:28 AM] [ INFO] TMDb catalog search for "Missing Pieces" from 2012 found 1 results.
[9/17/2021 4:30:28 AM] [ INFO] Processed 2763 movie poster links.
[9/17/2021 4:30:28 AM] Exiting GetAllMoviePosters(). SUCCESS.
[9/17/2021 4:30:28 AM] Entering GetAllSeriesImages() for 4805 series.
[9/17/2021 4:30:28 AM] [ INFO] Found 4481 cached/unavailable series image links.
[9/17/2021 4:30:29 AM] [ INFO] Successfully retrieved artwork info for 324 programs. (0:00:00:00.8800012 /     1.027 MB)
[9/17/2021 4:30:29 AM] [ INFO] Processed 4805 series image links.
[9/17/2021 4:30:29 AM] Exiting GetAllSeriesImages(). SUCCESS.
[9/17/2021 4:30:29 AM] Entering GetAllSeasonImages() for 4463 seasons.
[9/17/2021 4:30:29 AM] [ INFO] Found 4273 cached/unavailable season image links.
[9/17/2021 4:30:30 AM] [ INFO] Successfully retrieved artwork info for 190 programs. (0:00:00:00.6800009 /     1.673 MB)
[9/17/2021 4:30:30 AM] [ INFO] Processed 4463 season image links.
[9/17/2021 4:30:30 AM] Exiting GetAllSeasonImages(). SUCCESS.
[9/17/2021 4:30:30 AM] Entering GetAllSportsImages() for 0 sports events.
[9/17/2021 4:30:30 AM] [ INFO] Found 0 cached/unavailable sport event image links.
[9/17/2021 4:30:30 AM] [ INFO] Processed 0 sport event image links.
[9/17/2021 4:30:30 AM] Exiting GetAllSportsImages(). SUCCESS.
[9/17/2021 4:30:30 AM] [ INFO] Completed compiling keywords and keyword groups.
[9/17/2021 4:30:33 AM] [ INFO] Completed save of the MXF file to "C:\epg123\output\epg123.mxf".
[9/17/2021 4:30:33 AM] [ INFO] 3965 entries deleted from the cache file during cleanup.
[9/17/2021 4:30:34 AM] [ INFO] Downloaded and processed    14.870 MB of data from Schedules Direct.
[9/17/2021 4:30:34 AM] [ INFO] Generated .mxf file contains 380 services, 4805 series, 4463 seasons, 36226 programs, 131916 schedule entries, and 56266 people with 8594 image links.
[9/17/2021 4:30:34 AM] [ INFO] Completed EPG123 update execution. SUCCESS.
[9/17/2021 4:30:34 AM] [ INFO] epg123 update execution time was 00:00:34.6400485.
[9/17/2021 4:30:35 AM] ===============================================================================
[9/17/2021 4:30:35 AM]  Beginning epg123 client execution. version 1.7.0.2
[9/17/2021 4:30:35 AM] ===============================================================================
[9/17/2021 4:30:35 AM] [ INFO] Beginning epg123 client execution. 2021-09-17 08:30:35Z
[9/17/2021 4:30:35 AM] [ INFO] Import: True , Match: True , NoLogo: False , Force: False , ShowProgress: False , NoGC: False , NoVerify: False , Verbose: False
[9/17/2021 4:30:37 AM] [ INFO] There is a recording in progress or the next scheduled recording is within 60 minutes. Delaying garbage collection and/or import.
[9/17/2021 4:34:19 AM] Entering PerformGarbageCleanup().
[9/17/2021 4:59:40 AM] [ INFO] Successfully completed garbage cleanup. Exit code: 0
[9/17/2021 4:59:40 AM] Exiting PerformGarbageCleanup(). SUCCESS.
[9/17/2021 4:59:42 AM] Entering ImportMxfFile() for file "c:\epg123\output\epg123.mxf".
[9/17/2021 5:09:47 AM] [ INFO] Unhandled Exception: System.ObjectDisposedException: Cannot access a disposed object.
[9/17/2021 5:09:47 AM] [ INFO] Object name: 'ObjectStoreEvents is null'.
[9/17/2021 5:09:47 AM] [ INFO]    at Microsoft.MediaCenter.Store.CrashProtector.ThrowObjectDisposed(String objectName)
[9/17/2021 5:09:47 AM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsEnumerator..ctor(ObjectStore store, WhereClause whereClause, ObjectType typeElem, ObjectType returnType, UpdateMode updateMode)
[9/17/2021 5:09:47 AM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsEnumerator..ctor(ObjectStore store, WhereClause whereClause, ObjectType typeElem)
[9/17/2021 5:09:47 AM] [ INFO]    at MediaCenter.Store.OleDB.StoredObjectsRecord.NewEnumerator()
[9/17/2021 5:09:47 AM] [ INFO]    at Microsoft.MediaCenter.Store.StoredObjects.GetStoredObjectsEnumerator[T]()
[9/17/2021 5:09:47 AM] [ INFO]    at Microsoft.MediaCenter.Store.StoredObjects`1.System.Collections.Generic.IEnumerable<T>.GetEnumerator()
[9/17/2021 5:09:47 AM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.SchedulerCache.GetAssignmentGroups(RequestedProgram requestedProgram)
[9/17/2021 5:09:47 AM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.BranchAndBoundSchedulingStep.TryNext(PriorityListBase priorityList, PriorityQueue`1 queue, RequestedPrograms allRequestedPrograms, Int32 maxScheduleCount, Scheduler scheduler, BranchAndBoundSchedulingStep& next)
[9/17/2021 5:09:47 AM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.BranchAndBoundSchedulingAlgorithm(ScheduleBase schedule, PriorityListBase priorityList, IEnumerable`1 requestedPrograms, Int32 maxScheduleCount, ExpressionLockHandle`1 lockHandle)
[9/17/2021 5:09:47 AM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.<CreateSchedule>b__8(ScheduleParameters parameters, ExpressionLockHandle`1 lockHandle)
[9/17/2021 5:09:47 AM] [ INFO]    at Microsoft.MediaCenter.Pvr.OptimisticReader`3.Read(ReaderAction2 readerAction, P parameter, Expression expression)
[9/17/2021 5:09:47 AM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.CreateSchedule(ScheduleBase schedule, PriorityListBase priorityList, IEnumerable`1 requestedPrograms)
[9/17/2021 5:09:47 AM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.Schedule(IEnumerable`1 requestedPrograms)
[9/17/2021 5:09:47 AM] [ INFO]    at Microsoft.MediaCenter.Pvr.Scheduler.Schedule()
[9/17/2021 5:09:47 AM] [ INFO]    at Microsoft.MediaCenter.Pvr.Requests.UpdateAllActive(ObjectStore objectStore)
[9/17/2021 5:09:47 AM] [ INFO]    at Microsoft.MediaCenter.Pvr.FullRescheduleWorker.Work()
[9/17/2021 5:09:47 AM] [ INFO]    at Microsoft.MediaCenter.Pvr.SignaledWorker.ThreadProcess()
[9/17/2021 5:09:47 AM] [ INFO]    at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state)
[9/17/2021 5:09:47 AM] [ INFO]    at System.Threading.ThreadHelper.ThreadStart()
[9/17/2021 5:09:47 AM] [ERROR] Error using loadmxf.exe to import new guide information. Exit code: -532459699
[9/17/2021 5:09:47 AM] Exiting ImportMxfFile(). FAILURE.
[9/17/2021 5:09:47 AM] [ERROR] Failed to import .mxf file. Exiting.
[9/17/2021 5:09:47 AM] [ INFO] MXF file was created on 9/17/2021 4:30:00 AM
[9/17/2021 5:09:47 AM] [ INFO] Completed EPG123 client execution.
[9/17/2021 5:09:47 AM] [ INFO] EPG123 client execution time was 00:39:12.8072964.
[9/17/2021 6:19:59 AM] [ERROR] Error using loadmxf.exe to import new guide information. Exit code: 255
[9/17/2021 6:19:59 AM] Exiting ImportMxfFile(). FAILURE.
[9/17/2021 6:19:59 AM] [ERROR] Failed to import .mxf file. Exiting.
[9/17/2021 6:19:59 AM] [ INFO] MXF file was created on 9/17/2021 4:30:00 AM
[9/17/2021 6:19:59 AM] [ INFO] Completed EPG123 client execution.
[9/17/2021 6:19:59 AM] [ INFO] EPG123 client execution time was 11:35:00.7710881.
[9/17/2021 6:20:57 AM] [ INFO] **** Intercepted "mcupdate.exe -uf -nogc" call. Ignored. ****
[9/17/2021 6:28:57 AM] [ INFO] **** Intercepted "mcupdate.exe -u -nogc" call. Ignored. ****
[9/17/2021 6:44:21 AM] ===============================================================================
[9/17/2021 6:44:21 AM]  Activating the epg123 configuration GUI. version 1.6.7.0
[9/17/2021 6:44:21 AM] ===============================================================================
[9/17/2021 6:44:22 AM] [ INFO] epg123 is not up to date. Latest version is 1.7.0.2 and can be downloaded from https://github.com/garyan2/epg123/releases/tag/1.7.0.2
[9/17/2021 6:44:22 AM] [ INFO] Token request successful. serverID: 20141201.web.1 , datetime: 2021-09-17T10:44:21Z
[9/17/2021 6:44:22 AM] [ INFO] Status request successful. account expires: 2022-02-11T18:22:35Z , lineups: 2/4 , lastDataUpdate: 2021-09-17T01:15:09Z
[9/17/2021 6:44:22 AM] [ INFO] system status: Online , message: No known issues.
[9/17/2021 6:44:22 AM] [ INFO] Successfully requested listing of subscribed lineups from Schedules Direct.
[9/17/2021 6:44:22 AM] [ INFO] Successfully retrieved the station mapping for lineup USA-OTA-23233.
[9/17/2021 6:44:23 AM] [ INFO] Successfully retrieved the station mapping for lineup USA-VA45521-X.
[9/17/2021 6:44:23 AM] [ INFO] Successfully queried the Task Scheduler for status. Ready. Last Run 9/17/2021 4:30:00 AM; Exit: 0x00000000
[9/17/2021 6:44:34 AM] ===============================================================================
[9/17/2021 6:44:34 AM]  Beginning epg123 update execution. version 1.6.7.0
[9/17/2021 6:44:34 AM] ===============================================================================
[9/17/2021 6:44:34 AM] [ INFO] Beginning EPG123 update execution. 2021-09-17 10:44:34Z
[9/17/2021 6:44:34 AM] [ INFO] DaysToDownload: 14 , TheTVDBNumbers : False , PrefixEpisodeTitle: True , PrefixEpisodeDescription : True , AppendEpisodeDesc: True , OADOverride : True , TMDbCoverArt: True , IncludeSDLogos : False , AutoAddNew: True , CreateXmltv: False , ModernMediaUiPlusSupport: False
[9/17/2021 6:44:34 AM] [ INFO] Status request successful. account expires: 2022-02-11T18:22:35Z , lineups: 2/4 , lastDataUpdate: 2021-09-17T01:15:09Z
[9/17/2021 6:44:34 AM] [ INFO] system status: Online , message: No known issues.
[9/17/2021 6:44:34 AM] [ INFO] epg123 is not up to date. Latest version is 1.7.0.2 and can be downloaded from https://github.com/garyan2/epg123/releases/tag/1.7.0.2
[9/17/2021 6:44:36 AM] [ INFO] Successfully retrieved TMDb configurations.
[9/17/2021 6:44:37 AM] [ INFO] Successfully requested listing of subscribed lineups from Schedules Direct.
[9/17/2021 6:44:37 AM] Entering BuildLineupServices() for 2 lineups.
[9/17/2021 6:44:37 AM] [ INFO] Successfully retrieved the station mapping for lineup USA-OTA-23233.
[9/17/2021 6:44:37 AM] [ INFO] Successfully retrieved the station mapping for lineup USA-VA45521-X.
[9/17/2021 6:44:37 AM] Exiting BuildLineupServices(). SUCCESS.
[9/17/2021 6:44:37 AM] Entering GetAllScheduleEntryMd5s() for 14 days on 380 stations.
[9/17/2021 6:44:39 AM] [ INFO] Successfully retrieved Md5s for 357 station's daily schedules. (0:00:00:02.1100029 /   532.253 KB)
[9/17/2021 6:44:40 AM] [ INFO] Successfully retrieved Md5s for  23 station's daily schedules. (0:00:00:00.4500006 /    34.301 KB)
[9/17/2021 6:44:40 AM] [ INFO] Found 5320 cached daily schedules.
[9/17/2021 6:44:40 AM] [ INFO] Downloaded 0 daily schedules.
[9/17/2021 6:44:43 AM] [ INFO] Processed 5320 daily schedules for 380 stations.
[9/17/2021 6:44:43 AM] Exiting GetAllScheduleEntryMd5s(). SUCCESS.
[9/17/2021 6:44:43 AM] Entering BuildAllProgramEntries() for 36226 programs.
[9/17/2021 6:44:50 AM] [ INFO] Found 36226 cached program descriptions.
[9/17/2021 6:44:50 AM] [ INFO] Processed 36226 program descriptions.
[9/17/2021 6:44:50 AM] Exiting BuildAllProgramEntries(). SUCCESS.
[9/17/2021 6:44:50 AM] Entering BuildAllGenericSeriesInfoDescriptions() for 4805 series.
[9/17/2021 6:44:50 AM] [ INFO] Found 4805 cached/unavailable series descriptions.
[9/17/2021 6:44:50 AM] [ INFO] Processed 4805 series descriptions.
[9/17/2021 6:44:50 AM] Exiting BuildAllGenericSeriesInfoDescriptions(). SUCCESS.
[9/17/2021 6:44:50 AM] Entering GetAllMoviePosters() for 2763 movies.
[9/17/2021 6:44:50 AM] [ INFO] Found 2763 cached/unavailable movie poster links.
[9/17/2021 6:44:50 AM] [ INFO] Processed 2763 movie poster links.
[9/17/2021 6:44:50 AM] Exiting GetAllMoviePosters(). SUCCESS.
[9/17/2021 6:44:50 AM] Entering GetAllSeriesImages() for 4805 series.
[9/17/2021 6:44:50 AM] [ INFO] Found 4621 cached/unavailable series image links.
[9/17/2021 6:44:51 AM] [ INFO] Successfully retrieved artwork info for 184 programs. (0:00:00:00.5700008 /    73.287 KB)
[9/17/2021 6:44:51 AM] [ INFO] Processed 4805 series image links.
[9/17/2021 6:44:51 AM] Exiting GetAllSeriesImages(). SUCCESS.
[9/17/2021 6:44:51 AM] Entering GetAllSeasonImages() for 4463 seasons.
[9/17/2021 6:44:51 AM] [ INFO] Found 4386 cached/unavailable season image links.
[9/17/2021 6:44:51 AM] [ INFO] Successfully retrieved artwork info for  77 programs. (0:00:00:00.2600004 /   453.652 KB)
[9/17/2021 6:44:51 AM] [ INFO] Processed 4463 season image links.
[9/17/2021 6:44:51 AM] Exiting GetAllSeasonImages(). SUCCESS.
[9/17/2021 6:44:51 AM] Entering GetAllSportsImages() for 0 sports events.
[9/17/2021 6:44:51 AM] [ INFO] Found 0 cached/unavailable sport event image links.
[9/17/2021 6:44:51 AM] [ INFO] Processed 0 sport event image links.
[9/17/2021 6:44:51 AM] Exiting GetAllSportsImages(). SUCCESS.
[9/17/2021 6:44:51 AM] [ INFO] Completed compiling keywords and keyword groups.
[9/17/2021 6:44:54 AM] [ INFO] Completed save of the MXF file to "C:\epg123\output\epg123.mxf".
[9/17/2021 6:44:54 AM] [ INFO] 8 entries deleted from the cache file during cleanup.
[9/17/2021 6:44:56 AM] [ INFO] Downloaded and processed     1.068 MB of data from Schedules Direct.
[9/17/2021 6:44:56 AM] [ INFO] Generated .mxf file contains 380 services, 4805 series, 4463 seasons, 36226 programs, 131916 schedule entries, and 56266 people with 8594 image links.
[9/17/2021 6:44:56 AM] [ INFO] Completed EPG123 update execution. SUCCESS.
[9/17/2021 6:44:56 AM] [ INFO] epg123 update execution time was 00:00:21.6600521.
[9/17/2021 6:44:56 AM] ===============================================================================
[9/17/2021 6:44:56 AM]  Beginning epg123 client execution. version 1.6.7.0
[9/17/2021 6:44:56 AM] ===============================================================================
[9/17/2021 6:44:56 AM] [ INFO] Beginning epg123 client execution. 2021-09-17 10:44:56Z
[9/17/2021 6:44:56 AM] [ INFO] Import: True , Match: True , NoLogo: False , Force: False , ShowProgress: True , NoGC: True , NoVerify: True , Verbose: False
[9/17/2021 6:44:57 AM] Entering ImportMxfFile() for file "c:\epg123\output\epg123.mxf".
[9/17/2021 6:48:50 AM] [ INFO] Successfully imported .mxf file into Media Center database. Exit code: 0
[9/17/2021 6:48:50 AM] Exiting ImportMxfFile(). SUCCESS.
[9/17/2021 6:48:54 AM] [ INFO] Completed the automatch of lineup stations to tuner channels.
[9/17/2021 6:48:56 AM] [ INFO] Completed lineup refresh.
[9/17/2021 6:48:56 AM] Entering RunWmcIndexTask(PvrScheduleTask)
[9/17/2021 6:48:56 AM] [ INFO] SUCCESS: Attempted to run the scheduled task "Microsoft\Windows\Media Center\PvrScheduleTask".
[9/17/2021 6:48:56 AM] [ INFO] Successfully started the PvrScheduleTask task. Exit code: 0
[9/17/2021 6:48:56 AM] Exiting RunWmcIndexTask(PvrScheduleTask). SUCCESS.
[9/17/2021 6:48:56 AM] Entering RunWmcIndexTask(ReindexSearchRoot)
[9/17/2021 6:48:56 AM] [ INFO] SUCCESS: Attempted to run the scheduled task "Microsoft\Windows\Media Center\ReindexSearchRoot".
[9/17/2021 6:48:56 AM] [ INFO] Successfully started the ReindexSearchRoot task. Exit code: 0
[9/17/2021 6:48:56 AM] Exiting RunWmcIndexTask(ReindexSearchRoot). SUCCESS.
[9/17/2021 6:48:56 AM] [ INFO] MXF file was created on 9/17/2021 6:44:34 AM
[9/17/2021 6:48:56 AM] [ INFO] Completed EPG123 client execution.
[9/17/2021 6:48:56 AM] [ INFO] EPG123 client execution time was 00:04:00.2143728.
Last edited by cbarbie on Fri Sep 17, 2021 5:09 pm, edited 1 time in total.
Top
User avatar
garyan2

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

HTPC Specs: Show details

#27

Post by garyan2 » Fri Sep 17, 2021 2:25 pm

IT Troll wrote: ↑Fri Sep 17, 2021 6:04 am
garyan2 wrote: ↑Fri Sep 17, 2021 3:22 am Just a follow up. It wasn't the sequence of the installer but due not having a token in the registry when the service started. If that happens, then the service will assume we already tried to get a token and failed, so won't try again. That'll be fixed in next release (soon).
Thanks for the follow up. Glad to hear you identified the cause. I guess this will only affect always-on machines anyway.

Thanks also for the extra detail on the IMAGE_NOT_FOUND issue, hopefully SD can correct that. I’ve not directly compared it, but the initial image request and display does feel a little slower than it did before.
I'm trying to optimize the service now. It looks like I may have to go slower in order to go faster... I need to lower the number of concurrent connections in order to respond to each faster. At least I hope that will speed things up. The next release already has some improvements, but I think I can do better.

It is just slow going on my end since while in development I tend to hit the download limit and have to wait until the next day so I can continue testing.
- Gary
Keeping WMC alive beyond January 2020. https://garyan2.github.io
Top
User avatar
garyan2

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

HTPC Specs: Show details

#28

Post by garyan2 » Fri Sep 17, 2021 2:27 pm

cbarbie wrote: ↑Fri Sep 17, 2021 11:11 am I cleared the cache and tried again, same results MXF load failure. I wend back to 1.6.7.0 and it is working just like preupgrade to 1.7.0.2. Need any additional information or files? Log is below.
Thanks, this is a good data point. I will do a compare between 1.6.7.0 and 1.7.0.2. I can only think of 1 thing I did that would change the mxf file a little, but I will check to see if there are any others.
- Gary
Keeping WMC alive beyond January 2020. https://garyan2.github.io
Top
User avatar
garyan2

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

HTPC Specs: Show details

#29

Post by garyan2 » Fri Sep 17, 2021 4:50 pm

garyan2 wrote: ↑Fri Sep 17, 2021 2:27 pm
cbarbie wrote: ↑Fri Sep 17, 2021 11:11 am I cleared the cache and tried again, same results MXF load failure. I wend back to 1.6.7.0 and it is working just like preupgrade to 1.7.0.2. Need any additional information or files? Log is below.
Thanks, this is a good data point. I will do a compare between 1.6.7.0 and 1.7.0.2. I can only think of 1 thing I did that would change the mxf file a little, but I will check to see if there are any others.
I was actually able to get a similar error on one of my VM's. I backed out that small change the MXF file which was just removing the matchname of the channel. It appears WMC may not like having a non-empty matchname in the database and then removing it. I'm not sure since it doesn't make too much sense. I was able to import the MXF file after that but it took 2 tries. YMMV.

I just released v1.7.0.3 with the change if you want to give it a spin.
- Gary
Keeping WMC alive beyond January 2020. https://garyan2.github.io
Top
cbarbie

Posts: 25
Joined: Thu Mar 10, 2016 11:42 am
Location:

HTPC Specs: Show details

#30

Post by cbarbie » Fri Sep 17, 2021 5:08 pm

garyan2 wrote: ↑Fri Sep 17, 2021 4:50 pm
garyan2 wrote: ↑Fri Sep 17, 2021 2:27 pm
cbarbie wrote: ↑Fri Sep 17, 2021 11:11 am I cleared the cache and tried again, same results MXF load failure. I wend back to 1.6.7.0 and it is working just like preupgrade to 1.7.0.2. Need any additional information or files? Log is below.
Thanks, this is a good data point. I will do a compare between 1.6.7.0 and 1.7.0.2. I can only think of 1 thing I did that would change the mxf file a little, but I will check to see if there are any others.
I was actually able to get a similar error on one of my VM's. I backed out that small change the MXF file which was just removing the matchname of the channel. It appears WMC may not like having a non-empty matchname in the database and then removing it. I'm not sure since it doesn't make too much sense. I was able to import the MXF file after that but it took 2 tries. YMMV.

I just released v1.7.0.3 with the change if you want to give it a spin.
Installed the v1.7.0.3 change, rebooted, ran an update, and it worked with no problems. Thank you!

Code: Select all

[9/17/2021 12:57:11 PM] ===============================================================================
[9/17/2021 12:57:11 PM]  Activating the epg123 configuration GUI. version 1.7.0.3
[9/17/2021 12:57:11 PM] ===============================================================================
[9/17/2021 12:57:13 PM] [ INFO] Token request successful. serverID: 20141201.web.1 , datetime: 2021-09-17T16:57:12Z
[9/17/2021 12:57:13 PM] [ INFO] Status request successful. account expires: 2022-02-11T18:22:35Z , lineups: 2/4 , lastDataUpdate: 2021-09-17T16:40:50Z
[9/17/2021 12:57:13 PM] [ INFO] system status: Online , message: No known issues.
[9/17/2021 12:57:13 PM] [ INFO] Successfully requested listing of subscribed lineups from Schedules Direct.
[9/17/2021 12:57:13 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-OTA-23233.
[9/17/2021 12:57:13 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-VA45521-X.
[9/17/2021 12:57:14 PM] [ INFO] Successfully queried the Task Scheduler for status. Ready. Last Run 9/17/2021 4:30:00 AM; Exit: 0x00000000
[9/17/2021 12:57:34 PM] ===============================================================================
[9/17/2021 12:57:34 PM]  Beginning epg123 update execution. version 1.7.0.3
[9/17/2021 12:57:34 PM] ===============================================================================
[9/17/2021 12:57:34 PM] [ INFO] Beginning EPG123 update execution. 2021-09-17 16:57:34Z
[9/17/2021 12:57:34 PM] [ INFO] DaysToDownload: 14 , TheTVDBNumbers : False , PrefixEpisodeTitle: True , PrefixEpisodeDescription : True , AppendEpisodeDesc: True , OADOverride : True , TMDbCoverArt: True , IncludeSDLogos : False , AutoAddNew: True , CreateXmltv: False , ModernMediaUiPlusSupport: False
[9/17/2021 12:57:35 PM] [ INFO] Status request successful. account expires: 2022-02-11T18:22:35Z , lineups: 2/4 , lastDataUpdate: 2021-09-17T16:40:50Z
[9/17/2021 12:57:35 PM] [ INFO] system status: Online , message: No known issues.
[9/17/2021 12:57:39 PM] [ INFO] Successfully retrieved TMDb configurations.
[9/17/2021 12:57:39 PM] [ INFO] Successfully requested listing of subscribed lineups from Schedules Direct.
[9/17/2021 12:57:39 PM] Entering BuildLineupServices() for 2 lineups.
[9/17/2021 12:57:39 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-OTA-23233.
[9/17/2021 12:57:39 PM] [ INFO] Successfully retrieved the station mapping for lineup USA-VA45521-X.
[9/17/2021 12:57:39 PM] Exiting BuildLineupServices(). SUCCESS.
[9/17/2021 12:57:39 PM] Entering GetAllScheduleEntryMd5s() for 14 days on 380 stations.
[9/17/2021 12:57:45 PM] [ INFO] Successfully retrieved Md5s for 357 station's daily schedules. (0:00:00:05.7100080 /   532.211 KB)
[9/17/2021 12:57:54 PM] [ INFO] Successfully retrieved 212 station's daily schedules.          (0:00:00:08.1100113 /     5.979 MB)
[9/17/2021 12:57:58 PM] [ INFO] Successfully retrieved Md5s for  23 station's daily schedules. (0:00:00:00.4600006 /    34.294 KB)
[9/17/2021 12:57:59 PM] [ INFO] Successfully retrieved  16 station's daily schedules.          (0:00:00:00.7400010 /   638.626 KB)
[9/17/2021 12:57:59 PM] [ INFO] Found 4226 cached daily schedules.
[9/17/2021 12:57:59 PM] [ INFO] Downloaded 1094 daily schedules.
[9/17/2021 12:58:02 PM] [ INFO] Processed 5320 daily schedules for 380 stations.
[9/17/2021 12:58:02 PM] Exiting GetAllScheduleEntryMd5s(). SUCCESS.
[9/17/2021 12:58:02 PM] Entering BuildAllProgramEntries() for 36513 programs.
[9/17/2021 12:58:08 PM] [ INFO] Found 35663 cached program descriptions.
[9/17/2021 12:58:16 PM] [ INFO] Successfully retrieved  850 program descriptions. (0:00:00:07.2500112 /     1.553 MB)
[9/17/2021 12:58:16 PM] [ INFO] Processed 36513 program descriptions.
[9/17/2021 12:58:16 PM] Exiting BuildAllProgramEntries(). SUCCESS.
[9/17/2021 12:58:16 PM] Entering BuildAllGenericSeriesInfoDescriptions() for 4814 series.
[9/17/2021 12:58:17 PM] [ INFO] Found 4800 cached/unavailable series descriptions.
[9/17/2021 12:58:17 PM] [ INFO] Successfully retrieved  14 generic program descriptions. (0:00:00:00.1600002 /     3.307 KB)
[9/17/2021 12:58:17 PM] [ INFO] Processed 4814 series descriptions.
[9/17/2021 12:58:17 PM] Exiting BuildAllGenericSeriesInfoDescriptions(). SUCCESS.
[9/17/2021 12:58:17 PM] Entering GetAllMoviePosters() for 2765 movies.
[9/17/2021 12:58:17 PM] [ INFO] Found 2759 cached/unavailable movie poster links.
[9/17/2021 12:58:17 PM] [ INFO] Successfully retrieved artwork info for   6 programs. (0:00:00:00.0900001 /    86.148 KB)
[9/17/2021 12:58:17 PM] [ INFO] TMDb catalog search for "Taking the Reins" from 2021 found 1 results.
[9/17/2021 12:58:17 PM] [ INFO] Processed 2765 movie poster links.
[9/17/2021 12:58:17 PM] Exiting GetAllMoviePosters(). SUCCESS.
[9/17/2021 12:58:17 PM] Entering GetAllSeriesImages() for 4814 series.
[9/17/2021 12:58:17 PM] [ INFO] Found 4589 cached/unavailable series image links.
[9/17/2021 12:58:18 PM] [ INFO] Successfully retrieved artwork info for 225 programs. (0:00:00:00.5800009 /   277.935 KB)
[9/17/2021 12:58:18 PM] [ INFO] Processed 4814 series image links.
[9/17/2021 12:58:18 PM] Exiting GetAllSeriesImages(). SUCCESS.
[9/17/2021 12:58:18 PM] Entering GetAllSeasonImages() for 4509 seasons.
[9/17/2021 12:58:18 PM] [ INFO] Found 4418 cached/unavailable season image links.
[9/17/2021 12:58:19 PM] [ INFO] Successfully retrieved artwork info for  91 programs. (0:00:00:00.3700005 /   650.240 KB)
[9/17/2021 12:58:19 PM] [ INFO] Processed 4509 season image links.
[9/17/2021 12:58:19 PM] Exiting GetAllSeasonImages(). SUCCESS.
[9/17/2021 12:58:19 PM] Entering GetAllSportsImages() for 0 sports events.
[9/17/2021 12:58:19 PM] [ INFO] Found 0 cached/unavailable sport event image links.
[9/17/2021 12:58:19 PM] [ INFO] Processed 0 sport event image links.
[9/17/2021 12:58:19 PM] Exiting GetAllSportsImages(). SUCCESS.
[9/17/2021 12:58:19 PM] [ INFO] Completed compiling keywords and keyword groups.
[9/17/2021 12:58:21 PM] [ INFO] Completed save of the MXF file to "C:\epg123\output\epg123.mxf".
[9/17/2021 12:58:21 PM] [ INFO] 1758 entries deleted from the cache file during cleanup.
[9/17/2021 12:58:23 PM] [ INFO] Downloaded and processed     9.703 MB of data from Schedules Direct.
[9/17/2021 12:58:23 PM] [ INFO] Generated .mxf file contains 380 services, 4814 series, 4509 seasons, 36513 programs, 131916 schedule entries, and 56331 people with 8605 image links.
[9/17/2021 12:58:23 PM] [ INFO] Completed EPG123 update execution. SUCCESS.
[9/17/2021 12:58:23 PM] [ INFO] epg123 update execution time was 00:00:48.9700697.
[9/17/2021 12:58:24 PM] ===============================================================================
[9/17/2021 12:58:24 PM]  Beginning epg123 client execution. version 1.7.0.3
[9/17/2021 12:58:24 PM] ===============================================================================
[9/17/2021 12:58:24 PM] [ INFO] Beginning epg123 client execution. 2021-09-17 16:58:24Z
[9/17/2021 12:58:24 PM] [ INFO] Import: True , Match: True , NoLogo: False , Force: False , ShowProgress: True , NoGC: True , NoVerify: True , Verbose: False
[9/17/2021 12:58:26 PM] Entering ImportMxfFile() for file "c:\epg123\output\epg123.mxf".
[9/17/2021 1:00:25 PM] [ INFO] Successfully imported .mxf file into Media Center database. Exit code: 0
[9/17/2021 1:00:25 PM] Exiting ImportMxfFile(). SUCCESS.
[9/17/2021 1:00:27 PM] [ INFO] Completed the automatch of lineup stations to tuner channels.
[9/17/2021 1:00:29 PM] [ INFO] Completed lineup refresh.
[9/17/2021 1:00:29 PM] Entering RunWmcIndexTask(PvrScheduleTask)
[9/17/2021 1:00:29 PM] [ INFO] SUCCESS: Attempted to run the scheduled task "Microsoft\Windows\Media Center\PvrScheduleTask".
[9/17/2021 1:00:29 PM] [ INFO] Successfully started the PvrScheduleTask task. Exit code: 0
[9/17/2021 1:00:29 PM] Exiting RunWmcIndexTask(PvrScheduleTask). SUCCESS.
[9/17/2021 1:00:29 PM] Entering RunWmcIndexTask(ReindexSearchRoot)
[9/17/2021 1:00:29 PM] [ INFO] SUCCESS: Attempted to run the scheduled task "Microsoft\Windows\Media Center\ReindexSearchRoot".
[9/17/2021 1:00:29 PM] [ INFO] Successfully started the ReindexSearchRoot task. Exit code: 0
[9/17/2021 1:00:29 PM] Exiting RunWmcIndexTask(ReindexSearchRoot). SUCCESS.
[9/17/2021 1:00:29 PM] [ INFO] MXF file was created on 9/17/2021 12:57:34 PM
[9/17/2021 1:00:29 PM] [ INFO] Completed EPG123 client execution.
[9/17/2021 1:00:29 PM] [ INFO] EPG123 client execution time was 00:02:05.3321789.
Top
User avatar
garyan2

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

HTPC Specs: Show details

#31

Post by garyan2 » Fri Sep 17, 2021 5:31 pm

Weird. Thanks. I'll have to try and figure that out later.

Sent from my SM-G965U using Tapatalk

- Gary
Keeping WMC alive beyond January 2020. https://garyan2.github.io
Top
bsyd

Posts: 9
Joined: Tue Mar 18, 2014 4:15 pm
Location:

HTPC Specs: Show details

#32

Post by bsyd » Fri Sep 17, 2021 11:37 pm

I was having the same problem with v 1.7.0.2.
I installed v 1.7.0.3 and ran an update which ended successfully with no problems.

The new version solved the problem.
Thanks for the quick resolution Gary.
Top
User avatar
garyan2

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

HTPC Specs: Show details

#33

Post by garyan2 » Sat Sep 18, 2021 11:28 pm

There will be a 1.7.0.4 soon. I found that my throttle on conditional requests was not working so every image that is cached (if enabled) was making a conditional request to SD every time. This can seriously inflate the number of requests to SD and risk hitting the download limit.

I'm also trying to track down conditions in which responds with a 200 OK response for an image, but there is no image with the message. WMC could cache a 0-byte image so nothing will be shown. I was going to try out my code for this condition, but I hit my download limit and will have to try later.
- Gary
Keeping WMC alive beyond January 2020. https://garyan2.github.io
Top
User avatar
garyan2

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

HTPC Specs: Show details

#34

Post by garyan2 » Sun Sep 19, 2021 5:18 pm

1.7.0.4 is now released. Much better behavior wrt sending conditional requests to SD. I wasn't able to capture an empty download response from SD, so wasn't able to verify my code to capture that, but everything is working smooth now. As long as SD doesn't come up with a new way to <u>not</u> provide what is asked for, I think this will be the final tweak to the cache server.
- Gary
Keeping WMC alive beyond January 2020. https://garyan2.github.io
Top
User avatar
garyan2

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

HTPC Specs: Show details

#35

Post by garyan2 » Fri Oct 01, 2021 1:09 am

Just a peek into how the cache can help. This is after 5.25 days with multiple WMC clients and a program I use to check out my xmltv files that does not cache anything for itself, so everything is a "new" request each day.
stats2.PNG
I think avoiding nearly 17,000 requests (85% of total requests) to SD in that time period could be considered a great success. What is not shown is my cache only contains ~4,500 images.
- Gary
Keeping WMC alive beyond January 2020. https://garyan2.github.io
Top
Space

Posts: 2841
Joined: Sun Jun 02, 2013 9:44 pm
Location:

HTPC Specs: Show details

#36

Post by Space » Fri Oct 01, 2021 10:05 am

I have a few questions.

Are logo requests simply WMC requesting the logo through the EPG123 HTTP server instead of them being embedded in the MXF file? It appears that logo requests sent to the EPG123 HTTP server are not proxyed to SD (and just served from the local logo files which are downloaded during guide updates or manually).

What advantage (if any) is there to running the EPG123 HTTP cache if you only have the single WMC machine and no other client using the HTTP server? I know that WMC caches the images, but not sure how long it does so, or when it updates them. I think I remember you indicating that the cache should be enabled even in this scenario, is that (still?) true, and if so why?

How did you compute the 85% hit rate? I calculated a 96% hit rate (15664/(15664+688)= 0.957). Sure would be nice to have that on the status page. :angel:
Top
User avatar
garyan2

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

HTPC Specs: Show details

#37

Post by garyan2 » Fri Oct 01, 2021 2:34 pm

Correct on the logos. The service just provides the logo that is in the .\logos folder.

I'm trying to determine what I am seeing on my Win10 and a couple VMs, but my internet cache is being mostly cleared every day. WMC is not having anything cached at that point and everything needs to be downloaded again. I never noticed it doing that before, but I never had a reason to look before either. All the images that were downloaded previously, both from the cache and from SD are not there. If this is the way it works, then using the cache has obvious benefits even for a stand-alone. If anyone wants to verify if they are seeing the same thing, you can look at 'C:\Users\{user}\AppData\Local\Microsoft\Windows\Temporary Internet Files', or 'C:\Users\{user}\AppData\Local\Microsoft\Windows\INetCache' depending on OS.

The numbers I used was the number of requests for images (19313-106-40 = 19167) versus the number of requests forwarded to SD (2434). I did it this way because apparently right now, even the conditional requests sent to SD is counting against a maximum download limit they have in place. So the number of requests prevented from being sent to SD was 16733 (19167-2434). This gives 87.3% image requests handled by service/cache.
- Gary
Keeping WMC alive beyond January 2020. https://garyan2.github.io
Top
Space

Posts: 2841
Joined: Sun Jun 02, 2013 9:44 pm
Location:

HTPC Specs: Show details

#38

Post by Space » Sat Oct 02, 2021 7:59 am

So I've noticed that some series have different images for each season (such as "American Dad"), and others don't (or at least they use the same image for all the seasons in the guide). I did notice that "Bob's Burgers" has no images at all except for the current season (season 12). All the other seasons (you can find them on TBS, FXX, TOON) have no image and produce an IMAGE_NOT_FOUND in the server.log.

I assume this is an issue with SD, but I was wondering if EPG123 could fall back to grabbing/using the series image if the season image is unable to be downloaded. Doing this would be masking an issue with SD, even though the errors in the server.log would still be logged. So I'm not sure if you'd rather it be obvious there is a problem to the user, or leave the reporting/troubleshooting to the people who monitor their logs.

I've also noticed that I've been running for 2 days with the EPG123 cache turned on and I haven't had a single cache hit, so I assume the WMC cache is not being cleared on my system (I made sure to view images that I knew were previously downloaded to see if it had to re-download them). At least not every day. I've even exited and restarted the WMC GUI to see if that made any difference.
Top
User avatar
garyan2

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

HTPC Specs: Show details

#39

Post by garyan2 » Sat Oct 02, 2021 7:16 pm

Space wrote: ↑Sat Oct 02, 2021 7:59 am So I've noticed that some series have different images for each season (such as "American Dad"), and others don't (or at least they use the same image for all the seasons in the guide). I did notice that "Bob's Burgers" has no images at all except for the current season (season 12). All the other seasons (you can find them on TBS, FXX, TOON) have no image and produce an IMAGE_NOT_FOUND in the server.log.
This will be better for season images for series, as well as IMAGE_NOT_FOUND issues, in the future API update from SD. For right now to address the IMAGE_NOT_FOUND issue, this is probably due to the cached image link for epg123 is no longer valid. You can clear your cache and update to see if the "new" image is found. In the future this won't be an issue because the links to series/season/program images will be static even though the image might change.
Space wrote: ↑Sat Oct 02, 2021 7:59 am I assume this is an issue with SD, but I was wondering if EPG123 could fall back to grabbing/using the series image if the season image is unable to be downloaded. Doing this would be masking an issue with SD, even though the errors in the server.log would still be logged. So I'm not sure if you'd rather it be obvious there is a problem to the user, or leave the reporting/troubleshooting to the people who monitor their logs.
EPG123 has no way of knowing whether the image is available or not. You would think WMC would fallback to the series image if the season image link returns a 404, but I haven't seen evidence of that. If you think the service can do something, the answer is no. It is just a proxy and cache server... it has no idea what the image is for let alone what series and/or season.
Space wrote: ↑Sat Oct 02, 2021 7:59 am I've also noticed that I've been running for 2 days with the EPG123 cache turned on and I haven't had a single cache hit, so I assume the WMC cache is not being cleared on my system (I made sure to view images that I knew were previously downloaded to see if it had to re-download them). At least not every day. I've even exited and restarted the WMC GUI to see if that made any difference.
I've got to figure this out on my end. I just had a Win10 VM, with a populated INetCache for WMC clear out the cache after an update. Not sure how they could have been linked, but I haven't even opened WMC. I'm going to have to design some experiments to figure out what is going on here.
- Gary
Keeping WMC alive beyond January 2020. https://garyan2.github.io
Top
Space

Posts: 2841
Joined: Sun Jun 02, 2013 9:44 pm
Location:

HTPC Specs: Show details

#40

Post by Space » Sat Oct 02, 2021 10:59 pm

I cleared the EPG123 cache and now here is the "Bob's Burgers" image status:

Seasons with images: 9 11 12

Seasons without images: all others

So unless I missed that season 9 & 11 had images before (I don't think I did), it is the same as it was, except now season 9 & 11 have images as well. Note that there is only one episode in the guide for season 11.

It appears that WMC may have cached the non-images, as I don't see any counter increment in the EPG123 HTTP server status page when I view one of the episodes that is missing an image. I may just have to wait until it expires in the cache or clear the WMC cache. It does seem strange that WMC would cache a non-image, but perhaps it is to prevent many requests (that fail) from being sent.
Top
Post Reply

Return to “EPG123”