Detect and Remove Commercials Stopped Working After Plex Switched to New Guide (gracenote)

Live TV & DVR
1

Server Version#: 1.21.1.3876
Player Version#: 4.47.3
Tuner Make/Model: Hauppauge Win-TV QuadHD (Model 1651xx-1, Dual ATSC, QAM, IR)
Guide/Lineup name: Gracenote
Using XMLTV?: No
Channel number/Name: 6.1 CIII Global Toronto

Upgraded to Plex 1.21.1.3842-b0c7a97d9 after receiving notification of DVR Guide Change.
Deleted and recreated DVR, waited for guide refresh, rescheduled TV Show recordings. From this point forward, the "Detect and delete commericials " feature no longer works. I attempted to disable re-enable the “Detect and delete commercials” feature with no luck. Also attempted to copy comskip.ini from resources folder under the Plex Media Server install directory to DVR folder in Plex data dictionary under User Account’s AppData\Plex Media Server folder on the Window 10 Plex Media Server host also without luck. Also stopped and restarted Plex Media Server as well as shutdown and restart Windows 10. At this point, I would normally install prior version where this feature still worked and wait for Plex devs to resolve issue. However. prior version uses the abandoned TIVO guide provider so I am stuck.

I captured the following entry from Plex Media Server for one of the scheduled recordings and post processing is not initiating call to Plex Commercial Skipper.exe. .

Jan 22, 2021 17:29:59.782 [8240] DEBUG - Removed transcode data consumer, active count 3575 => 3575
Jan 22, 2021 17:29:59.921 [7288] DEBUG - Jobs: ‘C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe’ exit code for process 11376 is 0 (success)
Jan 22, 2021 17:29:59.921 [13292] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Recorder: No more consumers, stopping.
Jan 22, 2021 17:29:59.921 [13292] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Recorder: Asked to stop recording ‘TV Show Name - E** - TV Show Name’ on channel 6.1.
Jan 22, 2021 17:29:59.921 [13292] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Recorder: Stopping transcode session.
Jan 22, 2021 17:29:59.921 [13292] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Killing job.
Jan 22, 2021 17:29:59.921 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Recording for ‘TV Show Name - E** - TV Show Name’ on channel 6.1 stopped with status complete.
Jan 22, 2021 17:29:59.921 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Using recording status.
Jan 22, 2021 17:29:59.921 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Activity: updated activity fdb8fa63-ef0d-4744-a3e5-dc3b0e40d3f5 - completed 100.0% - Recording
Jan 22, 2021 17:29:59.921 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Activity: updated activity fdb8fa63-ef0d-4744-a3e5-dc3b0e40d3f5 - completed 100.0% - Recording
Jan 22, 2021 17:29:59.921 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] [MI] Opening input file: “F:\Media\TV Shows.grab\c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5-6cee8f942c3ad91b1a866aee980bec80a3882452*TV Show Name* (1973) - S48E** - TV Show Name.ts”
Jan 22, 2021 17:29:59.921 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] [FFMPEG] - Opening ‘F:\Media\TV Shows.grab\c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5-6cee8f942c3ad91b1a866aee980bec80a3882452*TV Show Name* (1973) - S48E** - TV Show Name.ts’ for reading
Jan 22, 2021 17:29:59.921 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] [FFMPEG] - Setting default whitelist ‘file,crypto’
Jan 22, 2021 17:29:59.921 [8768] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Activity: updated activity b1b4007b-53d6-4bb1-94b6-15f506b464e4 - completed 24.3% - Refreshing Sub
Jan 22, 2021 17:29:59.922 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] [FFMPEG] - Format mpegts probed with size=2048 and score=50
Jan 22, 2021 17:29:59.922 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] [FFMPEG] - stream=0 stream_type=2 pid=100 prog_reg_desc=
Jan 22, 2021 17:29:59.922 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] [FFMPEG] - stream=1 stream_type=81 pid=101 prog_reg_desc=
Jan 22, 2021 17:29:59.922 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] [FFMPEG] - stream=2 stream_type=81 pid=102 prog_reg_desc=
Jan 22, 2021 17:29:59.922 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] [FFMPEG] - Before avformat_find_stream_info() pos: 0 bytes read:32768 seeks:0 nb_streams:3
Jan 22, 2021 17:29:59.923 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] [FFMPEG] - All programs have pmt, headers found
Jan 22, 2021 17:29:59.924 [7288] DEBUG - Jobs: ‘C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe’ exit code for process 7056 is -1059143458 (intentional termination)
Jan 22, 2021 17:29:59.924 [13292] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Job was already killed, not killing again.
Jan 22, 2021 17:29:59.924 [13292] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Stopping transcode session 0b93dcee-cfcc-4cac-bd29-6bf353fb758f
Jan 22, 2021 17:29:59.924 [9052] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Grabber: Freed a tuner on device://tv.plex.grabbers.tunerservice/dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6b180070%26rev_04%236%2621215136%260%2600100008%23 (now 2 available)
Jan 22, 2021 17:29:59.924 [9052] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Cleaning directory for session 0b93dcee-cfcc-4cac-bd29-6bf353fb758f (C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-0b93dcee-cfcc-4cac-bd29-6bf353fb758f)
Jan 22, 2021 17:29:59.949 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] [FFMPEG] - Format yuv420p chosen by get_format().
Jan 22, 2021 17:29:59.967 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] [FFMPEG] - All info found
Jan 22, 2021 17:29:59.968 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] [FFMPEG] - After avformat_find_stream_info() pos: 0 bytes read:1888400 seeks:2 frames:102
Jan 22, 2021 17:29:59.968 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] [MI] * [mpegts mpeg2video/ac3] Duration: 3574720ms, 1920x1080 (1.777778) 29.970fps, bitrate=15015kbps, audio channels=6, optimizedForStreaming=0 (“F:\Media\TV Shows.grab\c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5-6cee8f942c3ad91b1a866aee980bec80a3882452*TV Show Name* (1973) - S48E** - TV Show Name.ts”)
Jan 22, 2021 17:29:59.968 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] [MI] Chapters had generic titles, clearing.
Jan 22, 2021 17:29:59.968 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] [MI] Closing input file: “F:\Media\TV Shows.grab\c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5-6cee8f942c3ad91b1a866aee980bec80a3882452*TV Show Name* (1973) - S48E** - TV Show Name.ts”
Jan 22, 2021 17:29:59.968 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Scheduling ‘TV Show Name - E** - TV Show Name’ for assimilation.
Jan 22, 2021 17:29:59.970 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Subscription: Resolved subscription to library item ‘TV Show Name’ (18249)
Jan 22, 2021 17:29:59.971 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] There were 1 top-level paths for TV Show Name.
Jan 22, 2021 17:29:59.971 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Assimilator: Postprocessing, we’re going to put *******TV Show Name******* - E** - *******TV Show Name******* in F:\Media\TV Shows\*******TV Show Name******* (1973)\Season 48\*******TV Show Name******* (1973) - S48E** - *******TV Show Name*******.ts
Jan 22, 2021 17:29:59.971 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Assimilator: Moving “F:\Media\TV Shows.grab\c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5-6cee8f942c3ad91b1a866aee980bec80a3882452*TV Show Name* (1973) - S48E** - TV Show Name.ts” to “F:\Media\TV Shows*TV Show Name* (1973)\Season 48*TV Show Name* (1973) - S48E** - TV Show Name.ts”
Jan 22, 2021 17:29:59.974 [9560] INFO - Library section 5 (TV Shows) will be updated because of a change in ‘“F:\Media\TV Shows*TV Show Name* (1973)\Season 48”/TV Show Name (1973) - S48E** - TV Show Name.ts’
Jan 22, 2021 17:29:59.988 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Subscription: Resolved subscription to library item ‘TV Show Name’ (18249)
Jan 22, 2021 17:30:00.066 [7008] DEBUG - DVR:Segmenter: Stopping idle session: http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6b180070%26rev_04%236%2621215136%260%2600100008%23/media/triplet%3A%2F%2F0%3A17215%3A2?param=<%3Fxml%20version%3D"1.0"%20encoding%3D"UTF-8"%3F> <tunedata><tt>8<%2Ftt><dq>0<%2Fdq><dr%2F><fr>23<%2Ffr><md>0<%2Fmd><sr>0<%2Fsr><pl>0<%2Fpl><lf>0<%2Flf><l1>0<%2Fl1><l2>0<%2Fl2><ls>0<%2Fls><pp>0<%2Fpp><hz>0<%2Fhz><in>0<%2Fin><fc>0<%2Ffc><t>17215<%2Ft><n>0<%2Fn><s>2<%2Fs><en>0<%2Fen><%2Ftunedata> .
Jan 22, 2021 17:30:00.082 [13292] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] HTTP requesting GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6b180070%26rev_04%236%2621215136%260%2600100008%23
Jan 22, 2021 17:30:00.086 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Added new metadata item (TV Show Name) with ID 22657
Jan 22, 2021 17:30:00.086 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Doing expensive tags write for ‘TV Show Name’ because something changed.
Jan 22, 2021 17:30:00.087 [6920] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] HubCache: Expiring ‘1/home.ondeck/1/hubs/en-caexternal-media,indirect-media/excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CVast%2CTopic&excludeFields=summary%2Ctagline%2Cfile&identifier=home%2Eondeck%2Chome%2Econtinue&includeEmpty=1&includeExternalMetadata=1&includeLibraryPlaylists=1&includeRecentChannels=1&includeStations=1&includeTypeFirst=1&libraryHubsOnly=1’ because of event plex.event.item.created.
Jan 22, 2021 17:30:00.091 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Updating metadata item (save) (TV Show Name) with ID 22657
Jan 22, 2021 17:30:00.113 [13292] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] HTTP 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6b180070%26rev_04%236%2621215136%260%2600100008%23
Jan 22, 2021 17:30:00.155 [3504] WARN - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Held transaction for too long (…\MediaProviders\Grabbers\MediaAssimilator.cpp:613): 0.140625 seconds
Jan 22, 2021 17:30:00.156 [4912] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Media Analyzer: Performing on-the-fly analysis on 1 item.
Jan 22, 2021 17:30:00.156 [4912] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Job running: set “EAE_ROOT=\?\C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\EasyAudioEncoder” & set “FFMPEG_EXTERNAL_LIBS=\\?\C:\Users\Administrator\AppData\Local\Plex\ Media\ Server\Codecs\367b3d4-3654-windows-x86\” & set “X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxx” & “C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Scanner.exe” --analyze --log-file-suffix " Analysis" --item 22657
Jan 22, 2021 17:30:00.186 [4912] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Jobs: Starting child process with pid 572
Jan 22, 2021 17:30:01.659 [11392] DEBUG - Request: [127.0.0.1:60847 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=5&metadataItemID=22657&metadataType=4&state=-1&mediaState=analyzing (4 live) GZIP Signed-in Token (@gmail.com)
Jan 22, 2021 17:30:01.659 [8240] DEBUG - Completed: [127.0.0.1:60847] 200 GET /:/metadata/notify/changeItemState?librarySectionID=5&metadataItemID=22657&metadataType=4&state=-1&mediaState=analyzing (4 live) GZIP 0ms 166 bytes
Jan 22, 2021 17:30:01.660 [11392] DEBUG - Request: [127.0.0.1:60850 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=5&metadataItemID=22657&metadataType=4&state=-1&mediaState=thumbnailing (4 live) GZIP Signed-in Token (@gmail.com)
Jan 22, 2021 17:30:01.660 [8240] DEBUG - Completed: [127.0.0.1:60850] 200 GET /:/metadata/notify/changeItemState?librarySectionID=5&metadataItemID=22657&metadataType=4&state=-1&mediaState=thumbnailing (4 live) GZIP 0ms 166 bytes
Jan 22, 2021 17:30:01.912 [3588] DEBUG - EPG[cloud]: Next thing to start/end is at 2021-01-22 22:51:00 GMT (in 1260 seconds)
Jan 22, 2021 17:30:02.801 [11392] DEBUG - Request: [127.0.0.1:60857 (Loopback)] GET /library/changestamp (2 live) GZIP Signed-in Token (@gmail.com)
Jan 22, 2021 17:30:02.801 [4880] DEBUG - Completed: [127.0.0.1:60857] 200 GET /library/changestamp (2 live) GZIP 0ms 469 bytes
Jan 22, 2021 17:30:02.897 [3556] DEBUG - Request: [127.0.0.1:60860 (Loopback)] GET /:/metadata/flushMetadataCounterCache (2 live) GZIP Signed-in Token (@gmail.com)
Jan 22, 2021 17:30:02.897 [8240] DEBUG - Completed: [127.0.0.1:60860] 200 GET /:/metadata/flushMetadataCounterCache (2 live) GZIP 0ms 166 bytes
Jan 22, 2021 17:30:02.898 [3556] DEBUG - Request: [127.0.0.1:60863 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=5&metadataItemID=22657&metadataType=4&state=-1&mediaState=idle (2 live) GZIP Signed-in Token (@gmail.com)
Jan 22, 2021 17:30:02.898 [8240] DEBUG - Completed: [127.0.0.1:60863] 200 GET /:/metadata/notify/changeItemState?librarySectionID=5&metadataItemID=22657&metadataType=4&state=-1&mediaState=idle (2 live) GZIP 0ms 166 bytes
Jan 22, 2021 17:30:02.934 [3556] DEBUG - Request: [127.0.0.1:60866 (Loopback)] GET /library/changestamp (2 live) GZIP Signed-in Token (@gmail.com)
Jan 22, 2021 17:30:02.934 [8240] DEBUG - Completed: [127.0.0.1:60866] 200 GET /library/changestamp (2 live) GZIP 0ms 470 bytes
Jan 22, 2021 17:30:02.941 [3556] DEBUG - Request: [127.0.0.1:60869 (Loopback)] GET /:/metadata/flushMetadataCounterCache (2 live) GZIP Signed-in Token (**********@gmail.com)
Jan 22, 2021 17:30:02.942 [4880] DEBUG - Completed: [127.0.0.1:60869] 200 GET /:/metadata/flushMetadataCounterCache (2 live) GZIP 0ms 166 bytes
Jan 22, 2021 17:30:03.040 [7288] DEBUG - Jobs: ‘C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Scanner.exe’ exit code for process 572 is 0 (success)
Jan 22, 2021 17:30:03.040 [4912] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Killing job.
Jan 22, 2021 17:30:03.040 [4912] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Media Analyzer: Background analysis completed in 2.9 seconds, removing 1 IDs
Jan 22, 2021 17:30:03.042 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Grabber: Assimilating bundle for ‘TV Show Name’ of type 4.
Jan 22, 2021 17:30:03.044 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] HTTP requesting HEAD https://metadata-static.plex.tv/3/gracenote/3a2d69c734cc349b524d67129af84f02.jpg
Jan 22, 2021 17:30:03.129 [9560] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] HTTP requesting GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6b180070%26rev_04%236%2621215136%260%2600100008%23
Jan 22, 2021 17:30:03.133 [9560] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] HTTP 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6b180070%26rev_04%236%2621215136%260%2600100008%23
Jan 22, 2021 17:30:03.258 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] HTTP 200 response from HEAD https://metadata-static.plex.tv/3/gracenote/3a2d69c734cc349b524d67129af84f02.jpg
Jan 22, 2021 17:30:03.258 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] HTTP requesting GET https://metadata-static.plex.tv/3/gracenote/3a2d69c734cc349b524d67129af84f02.jpg
Jan 22, 2021 17:30:03.448 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] HTTP 200 response from GET https://metadata-static.plex.tv/3/gracenote/3a2d69c734cc349b524d67129af84f02.jpg
Jan 22, 2021 17:30:03.449 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Media Downloader destroyed.
Jan 22, 2021 17:30:03.449 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Encoding user fields for ID 22657 as preface to save.
Jan 22, 2021 17:30:03.449 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Updating metadata item (save) (TV Show Name) with ID 22657
Jan 22, 2021 17:30:03.488 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Assimilator: Refreshing ‘TV Show Name - E** - TV Show Name’ (guid: com.plexapp.agents.thetvdb://70328?lang=en) with force: 1
Jan 22, 2021 17:30:03.489 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Refreshing metadata for ‘TV Show Name’ (agent: com.plexapp.agents.thetvdb)
Jan 22, 2021 17:30:03.489 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Photo transcoder: Request for url [/library/metadata/22657/thumb/1611354603] (is local: 1 upscaled: 0)
Jan 22, 2021 17:30:03.489 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Refreshing GUID: ‘com.plexapp.agents.thetvdb://70328?lang=en’
Jan 22, 2021 17:30:03.489 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] HTTP requesting GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=com.plexapp.agents.thetvdb%3A%2F%2F70328%3Flang%3Den&id=18249
Jan 22, 2021 17:30:03.504 [3556] DEBUG - Request: [127.0.0.1:60883 (Loopback)] GET /system/agents/update?mediaType=2&force=1&respectTags=0&guid=com%2Eplexapp%2Eagents%2Ethetvdb%3A%2F%2F70328%3Flang%3Den&id=18249 (2 live) GZIP Signed-in
Jan 22, 2021 17:30:03.504 [3556] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents/update?mediaType=2&force=1&respectTags=0&guid=com%2Eplexapp%2Eagents%2Ethetvdb%3A%2F%2F70328%3Flang%3Den&id=18249
Jan 22, 2021 17:30:03.504 [3556] DEBUG - HTTP requesting GET http://127.0.0.1:65120/system/agents/update?mediaType=2&force=1&respectTags=0&guid=com.plexapp.agents.thetvdb%3A%2F%2F70328%3Flang%3Den&id=18249
Jan 22, 2021 17:30:03.507 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Calculated media file path for path [media://9/5a091d4f7a02e563b3aa8667b13375947131b03.bundle/Contents/Thumbnails/thumb1.jpg]: [“C:\Users\Administrator\AppData\Local\Plex Media Server\Media\localhost/9/5a091d4f7a02e563b3aa8667b13375947131b03.bundle/Contents/Thumbnails/thumb1.jpg”]
Jan 22, 2021 17:30:03.507 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Jan 22, 2021 17:30:03.507 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Photo cache obtained 20237 bytes from /library/metadata/22657/thumb/1611354603
Jan 22, 2021 17:30:03.507 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Saving original media file to C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\4f\4fe1b82b00cbdde397f32fb3a4509e73bd3985d7.jpg
Jan 22, 2021 17:30:03.511 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Created thumbnail of size 100x56, has pixels: 1
Jan 22, 2021 17:30:03.521 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] BlurHashManager: Generated for item 22657 thumb in 31 ms.
Jan 22, 2021 17:30:03.521 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Photo transcoder: Request for url [/library/metadata/22657/art/1611354603] (is local: 1 upscaled: 0)
Jan 22, 2021 17:30:03.550 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Calculated media file path for path [upload://art\seasons\48\episodes\86/e8470da14c8783d6c5ddb8ebc653ec70c2f83d87.jpg]: [“C:\Users\Administrator\AppData\Local\Plex Media Server\Metadata\TV Shows\6\f80ae4bdb9fa7a8ebd9c5cf76b9ed7b216918ca.bundle\Uploads\art\seasons\48\episodes\86/e8470da14c8783d6c5ddb8ebc653ec70c2f83d87.jpg”]
Jan 22, 2021 17:30:03.551 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Jan 22, 2021 17:30:03.551 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Photo cache obtained 465363 bytes from /library/metadata/22657/art/1611354603
Jan 22, 2021 17:30:03.551 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Saving original media file to C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\8d\8d0534f6d6fba6e191ee1e547fa55a29f00fbaab.jpg
Jan 22, 2021 17:30:03.556 [3556] DEBUG - HTTP 200 response from GET http://127.0.0.1:65120/system/agents/update?mediaType=2&force=1&respectTags=0&guid=com.plexapp.agents.thetvdb%3A%2F%2F70328%3Flang%3Den&id=18249
Jan 22, 2021 17:30:03.556 [3556] DEBUG - [com.plexapp.system] HTTP reply status 200, with 2365 bytes of content.
Jan 22, 2021 17:30:03.557 [4880] DEBUG - Completed: [127.0.0.1:60883] 200 GET /system/agents/update?mediaType=2&force=1&respectTags=0&guid=com%2Eplexapp%2Eagents%2Ethetvdb%3A%2F%2F70328%3Flang%3Den&id=18249 (2 live) GZIP 53ms 988 bytes
Jan 22, 2021 17:30:03.557 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] HTTP 200 response from GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=com.plexapp.agents.thetvdb%3A%2F%2F70328%3Flang%3Den&id=18249
Jan 22, 2021 17:30:03.557 [3504] ERROR - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Error response for update: 5 (Unhandled exception).
Jan 22, 2021 17:30:03.564 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Created thumbnail of size 100x56, has pixels: 1
Jan 22, 2021 17:30:03.575 [2084] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] BlurHashManager: Generated for item 22657 art in 54 ms.
Jan 22, 2021 17:30:03.628 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Grabber: Operation for ‘TV Show Name - E** - TV Show Name’ on channel 6.1 completed with status complete (Success)
Jan 22, 2021 17:30:03.628 [3504] DEBUG - [Grabber/c3f0b375d54a53ff152fa2002ebeb7ddb9a703c5] Activity: Ended activity fdb8fa63-ef0d-4744-a3e5-dc3b0e40d3f5.

2

I can attest to this same issue! My Plex server is a Win 10, i7 machine. I have the Hauppauge QuadHD tuner as well. Plex commercial skip was working fine after January 1 until I deleted and recreated the DVR function around January 8. Since then, the commercial skip has been broken.

I initially had it set to detect and delete commercials. When I noticed it wasn’t working properly, I restarted the server, thinking that would fix it. No luck!

Next, I switched it to detect and mark for skip, and that didn’t work also.

I am not sure what else I can try except to wait for the next Plex release and see if it gets fixed.

I didn’t include log files, since I assume that they are similar to the one attached already. I can post them if needed.

Thanks for any help or suggestions you can provide.

3

I may have identified the cause of this issue, along with a solution. After recreating the DVR, I proceeded to reschedule all recording. This is where I went wrong! I should have went into the DVR settings and changed the default setting from “disabled” to “Detect and remove commercials” first! All rescheduled shows were rescheduled when the detect and remove commercials setting was disabled. Any show or movies that I scheduled after changing to “Detect and remove commercials” worked! So, the solution is to open up DVR schedule under Live TV and DVR, click on each show or movie, click modify recording, click advanced settings, scroll down the advanced settings to the commercials setting, and change it from ”Disabled” to “Detect and remove commercials” and then click “Save”. Repeat this for every movie or show in your list of upcoming shows in your schedule until you’ve changed them all. I have a show scheduled for tonight that I had to manually fix, so I’ll know for sure if this fixes the problem like I hope it does.

4

That sounds reasonable… I scheduled a “one time” record of a show on Sunday, and the commercials were deleted.

I went into the DVR schedule, and made the change you suggested. I am recording NCIS tonight, so I will quickly know if it works or not.

My fingers are crossed!

5

Commercial skip works when it wants to.
Playback from a browser and commercial skip failed for me so I clicked the 30+ sec button a few times and went to far.
Clicked the -10 sec button and it jumped forward by about a minute…
Used the mouse to click the timeline at the commercials and it didn’t move…
Close the file and restart at the recommended time.
Click the timeline for the commercials and it starts loading.
The commercial skip button shows up and I click it before the video loads.
Video playback starts after the break.
Skip button last till the end of the show but fails on the next episode…

6

Hi Kolchak,

Your issue is unrelated to this topic. Sounds like you are having issues with the “Detect commercial and mark for skip” feature and not about how to reschedule DVR recordings after being force to delete and recreate DVR when Plex switched from TiVo to Gracenote as EPG provider. I recommend that you open a new topic and include log file data so that Plex support can work with you to help resolve your problem.

7

Manually updating each scheduled recording after the fact worked for me. I am confident that it will work for you also.

8

Unrelated? The title of this topic is " [Detect and Remove Commercials Stopped Working After Plex Switched to New Guide (gracenote)", my post is about my recent experience with commercial marking under the new system.

9

You are free to try the solution I posted above. If you recreated your DVR and rescheduled you recordings before returning to DVR and selecting the “Detect and mark for skip” option, then the solution should work for you. My concern was that your problem might be unrelated because you mention seeing the skip markers in incorrect places; but go ahead. Try the solution posted above. I hope it works for you too.

10

The solution you posted worked for me. I went in and checked all my scheduled recordings, made the change and waited. Since then, all recordings have had the commercials skipped as we expect.

Thanks for your help!

11

This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.