Can't schedule a recording

General Discussions
1

I am trying to schedule a nightly recording of my local news, but somehow I cannot for the life of me get it to work. I can see the schedule on the Guide:

2019-10-16%2012_59_47-Plex
2019-10-16%2012_59_47-Plex931×141 38.8 KB

And I can go through the recording setup (New airings or repeat airings makes no difference):

2019-10-16%2013_00_15-Plex
2019-10-16%2013_00_15-Plex837×598 26.6 KB

Once it is scheduled, it looks like it’s ready to go on the guide:

2019-10-16%2013_00_21-Plex

But nothing shows up on the recording schedule. It should be scheduled to record TODAY at 6:30pm:

2019-10-16%2013_00_35-Plex
2019-10-16%2013_00_35-Plex899×469 291 KB

The time doesn’t conflict with anything else, so the scheduling priority is not an issue.

Log for approximately the time when I was scheduling the recording:

Oct 16, 2019 13:04:08.017 [13440] Debug — Auth: authenticated user 1 as rschuet
Oct 16, 2019 13:04:08.017 [140840] Debug — Request: [137.48.184.218:11457 (WAN)] POST /media/subscriptions?prefs%5BonlyNewAirings%5D=0&prefs%5BminVideoQuality%5D=0&prefs%5BreplaceLowerQuality%5D=false&prefs%5BrecordPartials%5D=true&prefs%5BstartOffsetMinutes%5D=0&prefs%5BendOffsetMinutes%5D=0&prefs%5BlineupChannel%5D=&prefs%5BstartTimeslot%5D=-1&prefs%5BcomskipEnabled%5D=-1&prefs%5BoneShot%5D=false&prefs%5BremoteMedia%5D=false&prefs%5BautoDeletionItemPolicyUnwatchedLibrary%5D=5&prefs%5BautoDeletionItemPolicyWatchedLibrary%5D=7&targetLibrarySectionID=2&targetSectionLocationID=3&includeGrabs=1&hints[guid]=plex%3A%2F%2Fshow%2F5cffa72b79f52f001d7929d8&hints[ratingKey]=plex%253A%252F%252Fshow%252F5cffa72b79f52f001d7929d8&hints[title]=WOWT%206%20News%20Live%20at%206%3A30&hints[type]=2&hints[year]=2018&params[airingChannels]=6%25252E1%253D6%25252E1%252520WOWTHD%252520(NBC)&params[airingTimes]=30&params[libraryType]=4&params[mediaProviderID]=4&type=2 (8 live) TLS GZIP Signed-in Token (rschuet)
Oct 16, 2019 13:04:08.020 [140840] Debug — DVR:Subscription: Resolved subscription to library item 'WOWT 6 News Live at 6:30' (1673)
Oct 16, 2019 13:04:08.022 [140840] Debug — Activity: registered new activity cab4ed78-8c50-4d73-bef3-d6e88a837f1c - Refreshing Sub
Oct 16, 2019 13:04:08.022 [140840] Debug — DVR:Subscription: Checking in provider Live TV & DVR
Oct 16, 2019 13:04:08.023 [140840] Debug — Downloading document http://127.0.0.1:32400/tv.plex.providers.epg.cloud:2/metadata/plex%3A%2F%2Fshow%2F5cffa72b79f52f001d7929d8?includePreferences=1
Oct 16, 2019 13:04:08.057 [140840] Debug — There were 0 top-level paths for WOWT 6 News Live at 6:30.
Oct 16, 2019 13:04:08.058 [140840] Debug — DVR:Subscription: The item at /tv.plex.providers.epg.cloud:2/metadata/plex%3A%2F%2Fshow%2F5cffa72b79f52f001d7929d8 is of type 2
Oct 16, 2019 13:04:08.058 [140840] Debug — Downloading document http://127.0.0.1:32400/tv.plex.providers.epg.cloud:2/metadata/plex%3A%2F%2Fshow%2F5cffa72b79f52f001d7929d8/grandchildren?includeElements=Media,Stream&includePreferences=1, paging as 0 + 100
Oct 16, 2019 13:04:08.060 [140840] Debug — Setting container serialization range to [0, 99] (total=-1)
Oct 16, 2019 13:04:08.066 [140840] Debug — Setting container serialization range to [0, 99] (total=1)
Oct 16, 2019 13:04:08.067 [140840] Debug — DVR:Subscription: There are 1 total episodes available, examining 1 items
Oct 16, 2019 13:04:08.067 [140840] Debug — DVR:Subscription: Episode 'WOWT 6 News Live at 6:30' airs on channel 4 at 2019-10-16 18:30:00
Oct 16, 2019 13:04:08.067 [140840] Debug — DVR:Subscription: Episode 'WOWT 6 News Live at 6:30' airs on channel 4 at 2019-10-17 18:30:00
Oct 16, 2019 13:04:08.067 [140840] Debug — DVR:Subscription: Episode 'WOWT 6 News Live at 6:30' airs on channel 4 at 2019-10-18 18:30:00
Oct 16, 2019 13:04:08.067 [140840] Debug — DVR:Subscription: Episode 'WOWT 6 News Live at 6:30' airs on channel 4 at 2019-10-19 18:30:00
Oct 16, 2019 13:04:08.067 [140840] Debug — DVR:Subscription: Episode 'WOWT 6 News Live at 6:30' airs on channel 4 at 2019-10-21 18:30:00
Oct 16, 2019 13:04:08.067 [140840] Debug — DVR:Subscription: Episode 'WOWT 6 News Live at 6:30' airs on channel 4 at 2019-10-22 18:30:00
Oct 16, 2019 13:04:08.067 [140840] Debug — DVR:Subscription: Episode 'WOWT 6 News Live at 6:30' airs on channel 4 at 2019-10-23 18:30:00
Oct 16, 2019 13:04:08.067 [140840] Debug — DVR:Subscription: Episode 'WOWT 6 News Live at 6:30' airs on channel 4 at 2019-10-24 18:30:00
Oct 16, 2019 13:04:08.067 [140840] Debug — DVR:Subscription: Episode 'WOWT 6 News Live at 6:30' airs on channel 4 at 2019-10-25 18:30:00
Oct 16, 2019 13:04:08.067 [140840] Debug — DVR:Subscription: Episode 'WOWT 6 News Live at 6:30' airs on channel 4 at 2019-10-26 18:30:00
Oct 16, 2019 13:04:08.068 [140840] Debug — DVR:Subscription: Episode 'WOWT 6 News Live at 6:30' airs on channel 4 at 2019-10-28 18:30:00
Oct 16, 2019 13:04:08.068 [140840] Debug — DVR:Subscription: Episode 'WOWT 6 News Live at 6:30' airs on channel 4 at 2019-10-29 18:30:00
Oct 16, 2019 13:04:08.070 [140840] Debug — DVR:Subscription: No match in the library for 'WOWT 6 News Live at 6:30 - WOWT 6 News Live at 6:30', grabbing.
Oct 16, 2019 13:04:08.070 [140840] Debug — Activity: updated activity cab4ed78-8c50-4d73-bef3-d6e88a837f1c - completed 0.0% - Refreshing Sub
Oct 16, 2019 13:04:08.070 [140840] Debug — Activity: registered new activity e646083d-d47f-4089-877a-28efcbcb3816 - Processing subscriptions
Oct 16, 2019 13:04:08.071 [140840] Debug — DVR:Subscription: Scheduling subscriptions.
Oct 16, 2019 13:04:08.076 [140840] Debug — DVR:Subscription: Initialized 1 schedulers.
Oct 16, 2019 13:04:08.076 [140840] Debug — DVR:Subscription: Starting with 7 grabs, scheduled 0 active ones preemptively.
Oct 16, 2019 13:04:08.091 [140840] Debug — DVR:NewSchedule: Partitioning, step one, let's compute an absolute ordering.
Oct 16, 2019 13:04:08.091 [140840] Debug —  DVR:NewSchedule: Between 2019-10-16 18:30:00 and 2019-10-16 19:00:00 on channel 6.1: 'WOWT 6 News Live at 6:30 - WOWT 6 News Live at 6:30'
Oct 16, 2019 13:04:08.091 [140840] Debug —  DVR:NewSchedule: Between 2019-10-16 20:01:00 and 2019-10-16 21:00:00 on channel 42.1: 'Almost Family - E3 - Notorious AF'
Oct 16, 2019 13:04:08.091 [140840] Debug —  DVR:NewSchedule: Between 2019-10-16 20:31:00 and 2019-10-16 21:00:00 on channel 7.1: 'Single Parents - E4 - Big Widow Wives'
Oct 16, 2019 13:04:08.091 [140840] Debug —  DVR:NewSchedule: Between 2019-10-17 19:00:00 and 2019-10-17 19:30:00 on channel 6.1: 'Superstore - E4 - Mall Closing'
Oct 16, 2019 13:04:08.091 [140840] Debug —  DVR:NewSchedule: Between 2019-10-17 20:00:00 and 2019-10-17 20:31:00 on channel 6.1: 'The Good Place - E4 - Tinker, Tailor, Demon, Spy'
Oct 16, 2019 13:04:08.091 [140840] Debug —  DVR:NewSchedule: Between 2019-10-23 20:31:00 and 2019-10-23 21:00:00 on channel 7.1: 'Single Parents - E5 - Sport'
Oct 16, 2019 13:04:08.091 [140840] Debug —  DVR:NewSchedule: Between 2019-10-24 19:00:00 and 2019-10-24 19:30:00 on channel 6.1: 'Superstore - Self-Care'
Oct 16, 2019 13:04:08.092 [140840] Debug —  DVR:NewSchedule: Between 2019-10-24 20:00:00 and 2019-10-24 20:31:00 on channel 6.1: 'The Good Place - E5 - Employee of the Bearimy'
Oct 16, 2019 13:04:08.092 [140840] Debug — DVR:NewSchedule: Initial partitioning completed with 7 partitions.
Oct 16, 2019 13:04:08.092 [140840] Debug — DVR:NewSchedule: Partitioning completed with 7 partitions.
Oct 16, 2019 13:04:08.092 [140840] Debug — DVR:NewSchedule: PARTITION of size 1.
Oct 16, 2019 13:04:08.092 [140840] Debug —  DVR:NewSchedule:    Between 2019-10-16 18:30:00 and 2019-10-16 19:00:00 on channel 6.1: 'WOWT 6 News Live at 6:30 - WOWT 6 News Live at 6:30'
Oct 16, 2019 13:04:08.092 [140840] Debug — DVR:NewSchedule: PARTITION of size 2.
Oct 16, 2019 13:04:08.092 [140840] Debug —  DVR:NewSchedule:    Between 2019-10-16 20:31:00 and 2019-10-16 21:00:00 on channel 7.1: 'Single Parents - E4 - Big Widow Wives'
Oct 16, 2019 13:04:08.093 [140840] Debug —  DVR:NewSchedule:    Between 2019-10-16 20:01:00 and 2019-10-16 21:00:00 on channel 42.1: 'Almost Family - E3 - Notorious AF'
Oct 16, 2019 13:04:08.093 [140840] Debug — DVR:NewSchedule: PARTITION of size 1.
Oct 16, 2019 13:04:08.093 [140840] Debug —  DVR:NewSchedule:    Between 2019-10-17 19:00:00 and 2019-10-17 19:30:00 on channel 6.1: 'Superstore - E4 - Mall Closing'
Oct 16, 2019 13:04:08.093 [140840] Debug — DVR:NewSchedule: PARTITION of size 1.
Oct 16, 2019 13:04:08.093 [140840] Debug —  DVR:NewSchedule:    Between 2019-10-17 20:00:00 and 2019-10-17 20:31:00 on channel 6.1: 'The Good Place - E4 - Tinker, Tailor, Demon, Spy'
Oct 16, 2019 13:04:08.093 [140840] Debug — DVR:NewSchedule: PARTITION of size 1.
Oct 16, 2019 13:04:08.093 [140840] Debug —  DVR:NewSchedule:    Between 2019-10-23 20:31:00 and 2019-10-23 21:00:00 on channel 7.1: 'Single Parents - E5 - Sport'
Oct 16, 2019 13:04:08.093 [140840] Debug — DVR:NewSchedule: PARTITION of size 1.
Oct 16, 2019 13:04:08.093 [140840] Debug —  DVR:NewSchedule:    Between 2019-10-24 19:00:00 and 2019-10-24 19:30:00 on channel 6.1: 'Superstore - Self-Care'
Oct 16, 2019 13:04:08.093 [140840] Debug — DVR:NewSchedule: PARTITION of size 1.
Oct 16, 2019 13:04:08.093 [140840] Debug —  DVR:NewSchedule:    Between 2019-10-24 20:00:00 and 2019-10-24 20:31:00 on channel 6.1: 'The Good Place - E5 - Employee of the Bearimy'
Oct 16, 2019 13:04:08.093 [140840] Debug — DVR:NewSchedule: After processing the partition, we have 24 alternate realities.
Oct 16, 2019 13:04:08.093 [140840] Debug — DVR:NewSchedule: After processing the partition, we have 4 alternate realities.
Oct 16, 2019 13:04:08.093 [140840] Debug — DVR:NewSchedule: After processing the partition, we have 4 alternate realities.
Oct 16, 2019 13:04:08.094 [140840] Debug — DVR:NewSchedule: After processing the partition, we have 4 alternate realities.
Oct 16, 2019 13:04:08.094 [140840] Debug — DVR:NewSchedule: After processing the partition, we have 4 alternate realities.
Oct 16, 2019 13:04:08.094 [140840] Debug — DVR:NewSchedule: After processing the partition, we have 4 alternate realities.
Oct 16, 2019 13:04:08.094 [140840] Debug — DVR:NewSchedule: After processing the partition, we have 4 alternate realities.
Oct 16, 2019 13:04:08.094 [140840] Debug — DVR:NewSchedule: We computed 36 alternative realities in 0 ms.
Oct 16, 2019 13:04:08.094 [140840] Debug — DVR:NewSchedule: The winning schedule had score: 96 and 0 conflicts.
Oct 16, 2019 13:04:08.094 [140840] Debug —  DVR:NewSchedule: device 0 tuner 0 airing 0 between 2019-10-16 18:30:00 and 2019-10-16 19:00:00 on channel 6.1: 'WOWT 6 News Live at 6:30 - WOWT 6 News Live at 6:30'
Oct 16, 2019 13:04:08.094 [140840] Debug —  DVR:NewSchedule: device 0 tuner 0 airing 0 between 2019-10-16 20:31:00 and 2019-10-16 21:00:00 on channel 7.1: 'Single Parents - E4 - Big Widow Wives'
Oct 16, 2019 13:04:08.094 [140840] Debug —  DVR:NewSchedule: device 0 tuner 1 airing 0 between 2019-10-16 20:01:00 and 2019-10-16 21:00:00 on channel 42.1: 'Almost Family - E3 - Notorious AF'
Oct 16, 2019 13:04:08.094 [140840] Debug —  DVR:NewSchedule: device 0 tuner 0 airing 0 between 2019-10-17 19:00:00 and 2019-10-17 19:30:00 on channel 6.1: 'Superstore - E4 - Mall Closing'
Oct 16, 2019 13:04:08.094 [140840] Debug —  DVR:NewSchedule: device 0 tuner 0 airing 0 between 2019-10-17 20:00:00 and 2019-10-17 20:31:00 on channel 6.1: 'The Good Place - E4 - Tinker, Tailor, Demon, Spy'
Oct 16, 2019 13:04:08.094 [140840] Debug —  DVR:NewSchedule: device 0 tuner 0 airing 0 between 2019-10-23 20:31:00 and 2019-10-23 21:00:00 on channel 7.1: 'Single Parents - E5 - Sport'
Oct 16, 2019 13:04:08.094 [140840] Debug —  DVR:NewSchedule: device 0 tuner 0 airing 0 between 2019-10-24 19:00:00 and 2019-10-24 19:30:00 on channel 6.1: 'Superstore - Self-Care'
Oct 16, 2019 13:04:08.094 [140840] Debug —  DVR:NewSchedule: device 0 tuner 0 airing 0 between 2019-10-24 20:00:00 and 2019-10-24 20:31:00 on channel 6.1: 'The Good Place - E5 - Employee of the Bearimy'
Oct 16, 2019 13:04:08.094 [140840] Debug — DVR:Grabber: Scheduling 'WOWT 6 News Live at 6:30 - WOWT 6 News Live at 6:30' for timed grab at 2019-10-16 18:30:00 (in 19552 seconds)
Oct 16, 2019 13:04:08.095 [140840] Debug — DVR:Grabber: Scheduling 'Single Parents - E4 - Big Widow Wives' for timed grab at 2019-10-16 20:31:00 (in 26812 seconds)
Oct 16, 2019 13:04:08.095 [140840] Debug — DVR:Grabber: Scheduling 'Almost Family - E3 - Notorious AF' for timed grab at 2019-10-16 20:01:00 (in 25012 seconds)
Oct 16, 2019 13:04:08.095 [140840] Debug — DVR:Grabber: Scheduling 'Superstore - E4 - Mall Closing' for timed grab at 2019-10-17 19:00:00 (in 107752 seconds)
Oct 16, 2019 13:04:08.095 [140840] Debug — DVR:Grabber: Scheduling 'The Good Place - E4 - Tinker, Tailor, Demon, Spy' for timed grab at 2019-10-17 20:00:00 (in 111352 seconds)
Oct 16, 2019 13:04:08.095 [140840] Debug — DVR:Grabber: Scheduling 'Single Parents - E5 - Sport' for timed grab at 2019-10-23 20:31:00 (in 631612 seconds)
Oct 16, 2019 13:04:08.095 [140840] Debug — DVR:Grabber: Scheduling 'Superstore - Self-Care' for timed grab at 2019-10-24 19:00:00 (in 712552 seconds)
Oct 16, 2019 13:04:08.095 [140840] Debug — DVR:Grabber: Scheduling 'The Good Place - E5 - Employee of the Bearimy' for timed grab at 2019-10-24 20:00:00 (in 716152 seconds)
Oct 16, 2019 13:04:08.095 [140840] Debug — DVR:Subscription: Scheduled all subscriptions in 24 ms.
Oct 16, 2019 13:04:08.095 [140840] Debug — Activity: Ended activity e646083d-d47f-4089-877a-28efcbcb3816.
Oct 16, 2019 13:04:08.097 [140840] Debug — DVR:Subscription: There are 8 active grabs at the end.
Oct 16, 2019 13:04:08.099 [13440] Debug — Completed: [137.48.184.218:11457] 200 POST /media/subscriptions?prefs%5BonlyNewAirings%5D=0&prefs%5BminVideoQuality%5D=0&prefs%5BreplaceLowerQuality%5D=false&prefs%5BrecordPartials%5D=true&prefs%5BstartOffsetMinutes%5D=0&prefs%5BendOffsetMinutes%5D=0&prefs%5BlineupChannel%5D=&prefs%5BstartTimeslot%5D=-1&prefs%5BcomskipEnabled%5D=-1&prefs%5BoneShot%5D=false&prefs%5BremoteMedia%5D=false&prefs%5BautoDeletionItemPolicyUnwatchedLibrary%5D=5&prefs%5BautoDeletionItemPolicyWatchedLibrary%5D=7&targetLibrarySectionID=2&targetSectionLocationID=3&includeGrabs=1&hints[guid]=plex%3A%2F%2Fshow%2F5cffa72b79f52f001d7929d8&hints[ratingKey]=plex%253A%252F%252Fshow%252F5cffa72b79f52f001d7929d8&hints[title]=WOWT%206%20News%20Live%20at%206%3A30&hints[type]=2&hints[year]=2018&params[airingChannels]=6%25252E1%253D6%25252E1%252520WOWTHD%252520(NBC)&params[airingTimes]=30&params[libraryType]=4&params[mediaProviderID]=4&type=2 (8 live) TLS GZIP 81ms 2339 bytes (pipelined: 16)
Oct 16, 2019 13:04:08.224 [13440] Debug — Auth: authenticated user 1 as rschuet
Oct 16, 2019 13:04:08.224 [140484] Debug — Request: [137.48.184.218:11457 (WAN)] GET /tv.plex.providers.epg.cloud:2/grid?type=1%2C4&sort=beginsAt&endsAt%3E=1571248800&beginsAt%3C=1571272200 (8 live) TLS GZIP Signed-in Token (rschuet)
Oct 16, 2019 13:04:08.226 [13440] Debug — Auth: authenticated user 1 as rschuet
Oct 16, 2019 13:04:08.226 [140736] Debug — Request: [137.48.184.218:11482 (WAN)] GET /tv.plex.providers.epg.cloud:2/tags?type=310 (8 live) TLS GZIP Signed-in Token (rschuet)
Oct 16, 2019 13:04:08.228 [13436] Debug — Completed: [137.48.184.218:11482] 200 GET /tv.plex.providers.epg.cloud:2/tags?type=310 (8 live) TLS GZIP 2ms 1629 bytes (pipelined: 14)
Oct 16, 2019 13:04:08.327 [13440] Debug — Completed: [137.48.184.218:11457] 200 GET /tv.plex.providers.epg.cloud:2/grid?type=1%2C4&sort=beginsAt&endsAt%3E=1571248800&beginsAt%3C=1571272200 (8 live) TLS GZIP 103ms 76531 bytes (pipelined: 17)
Oct 16, 2019 13:04:25.234 [136524] Debug — BPQ: onConsiderProcessing: Idle (true)
Oct 16, 2019 13:04:25.234 [136524] Debug — BPQ: [Idle] -> [Processing]
Oct 16, 2019 13:04:25.235 [136524] Debug — BPQ: generating queue items from 9 generator(s)
Oct 16, 2019 13:04:25.236 [136524] Error — Sync: unknown playlist com.plexapp.agents.none://b0367e14-6b6a-472b-8087-1de5bc3487d9
Oct 16, 2019 13:04:25.236 [136524] Error — Sync: skipping items for sync list 17201131, sync item 23791952: unable to generate sync set query
Oct 16, 2019 13:04:25.237 [136524] Error — LPE: unknown item 7837.
Oct 16, 2019 13:04:25.237 [136524] Error — Sync: failed to generate query for path library://831ff4e1-d1f7-4af6-9882-407fbd62e179/directory/%2Flibrary%2Fmetadata%2F7837%2Fchildren in sync item 24242360
Oct 16, 2019 13:04:25.237 [136524] Error — Sync: skipping items for sync list 17201131, sync item 24242360: unable to generate sync set query
Oct 16, 2019 13:04:25.237 [136524] Error — LPE: unknown item 7824.
Oct 16, 2019 13:04:25.237 [136524] Error — Sync: failed to generate query for path library://831ff4e1-d1f7-4af6-9882-407fbd62e179/directory/%2Flibrary%2Fmetadata%2F7824%2Fchildren in sync item 24242364
Oct 16, 2019 13:04:25.237 [136524] Error — Sync: skipping items for sync list 17201131, sync item 24242364: unable to generate sync set query
Oct 16, 2019 13:04:25.238 [136524] Debug — Sync: skipping sync list 35360193 from device Ryan’s Beast iPad  because it's snoozing
Oct 16, 2019 13:04:25.238 [136524] Debug — Sync: updating status for sync list 35360193, sync item 37448531, generator 261
Oct 16, 2019 13:04:25.239 [136524] Debug — Sync: skipping sync list 35360193 from device Ryan’s Beast iPad  because it's snoozing
Oct 16, 2019 13:04:25.239 [136524] Debug — Sync: updating status for sync list 35360193, sync item 37448532, generator 262
Oct 16, 2019 13:04:25.241 [136524] Debug — Sync: skipping sync list 35360193 from device Ryan’s Beast iPad  because it's snoozing
Oct 16, 2019 13:04:25.241 [136524] Debug — Sync: updating status for sync list 35360193, sync item 37448538, generator 263
Oct 16, 2019 13:04:25.242 [136524] Debug — Sync: skipping sync list 35360193 from device Ryan’s Beast iPad  because it's snoozing
Oct 16, 2019 13:04:25.242 [136524] Debug — Sync: updating status for sync list 35360193, sync item 38158334, generator 282
Oct 16, 2019 13:04:25.243 [136524] Debug — Sync: skipping sync list 35360193 from device Ryan’s Beast iPad  because it's snoozing
Oct 16, 2019 13:04:25.244 [136524] Debug — Sync: updating status for sync list 35360193, sync item 38158335, generator 283
Oct 16, 2019 13:04:25.244 [136524] Error — LPE: unknown item 4073.
Oct 16, 2019 13:04:25.244 [136524] Error — Sync: failed to generate query for path library://aaba04a9-5a58-4b16-9b9c-4e47711c1caa/item/%2Flibrary%2Fmetadata%2F4073 in sync item 38158335
Oct 16, 2019 13:04:25.244 [136524] Error — Sync: empty sync set, unable to update status for sync list 35360193, sync item 38158335
Oct 16, 2019 13:04:25.244 [136524] Debug — Sync: skipping sync list 35360193 from device Ryan’s Beast iPad  because it's snoozing
Oct 16, 2019 13:04:25.245 [136524] Debug — Sync: updating status for sync list 35360193, sync item 38158358, generator 284
Oct 16, 2019 13:04:25.246 [136524] Debug — BPQ: generated 0 item(s) for queue
Oct 16, 2019 13:04:25.246 [136524] Debug — PlayQueue: 0 generated IDs compressed down to a 20 byte blob.
Oct 16, 2019 13:04:25.246 [136524] Debug — PlayQueue: 0 generated IDs compressed down to a 20 byte blob.
Oct 16, 2019 13:04:25.246 [136524] Debug — BPQ: [Processing] -> [Idle]
Oct 16, 2019 13:04:25.247 [136524] Debug — BPQ: garbageCollect - sync - forced - 1
Oct 16, 2019 13:04:25.247 [136524] Debug — Sync: starting Sync garbage collection: 1, forced
Oct 16, 2019 13:04:25.247 [136524] Debug — Sync: garbage collecting database for sync list 17201131
Oct 16, 2019 13:04:25.247 [136524] Error — Sync: unknown playlist com.plexapp.agents.none://b0367e14-6b6a-472b-8087-1de5bc3487d9
Oct 16, 2019 13:04:25.247 [136524] Warning — Sync: sync item 23791952 has an empty sync set query, assuming sync item is no longer valid
Oct 16, 2019 13:04:25.248 [136524] Error — LPE: unknown item 7837.
Oct 16, 2019 13:04:25.248 [136524] Error — Sync: failed to generate query for path library://831ff4e1-d1f7-4af6-9882-407fbd62e179/directory/%2Flibrary%2Fmetadata%2F7837%2Fchildren in sync item 24242360
Oct 16, 2019 13:04:25.248 [136524] Warning — Sync: sync item 24242360 has an empty sync set query, assuming sync item is no longer valid
Oct 16, 2019 13:04:25.249 [136524] Error — LPE: unknown item 7824.
Oct 16, 2019 13:04:25.249 [136524] Error — Sync: failed to generate query for path library://831ff4e1-d1f7-4af6-9882-407fbd62e179/directory/%2Flibrary%2Fmetadata%2F7824%2Fchildren in sync item 24242364
Oct 16, 2019 13:04:25.249 [136524] Warning — Sync: sync item 24242364 has an empty sync set query, assuming sync item is no longer valid
Oct 16, 2019 13:04:25.249 [136524] Debug — Sync: garbage collected database for sync list 17201131 in 0.0 seconds
Oct 16, 2019 13:04:25.249 [136524] Debug — Sync: garbage collecting database for sync list 35360193
Oct 16, 2019 13:04:25.254 [136524] Error — LPE: unknown item 4073.
Oct 16, 2019 13:04:25.254 [136524] Error — Sync: failed to generate query for path library://aaba04a9-5a58-4b16-9b9c-4e47711c1caa/item/%2Flibrary%2Fmetadata%2F4073 in sync item 38158335
Oct 16, 2019 13:04:25.254 [136524] Warning — Sync: sync item 38158335 has an empty sync set query, assuming sync item is no longer valid
Oct 16, 2019 13:04:25.255 [136524] Debug — Sync: garbage collected database for sync list 35360193 in 0.0 seconds
Oct 16, 2019 13:04:25.255 [136524] Debug — Sync: finished Sync garbage collection: 1
Oct 16, 2019 13:04:25.255 [136524] Debug — BPQ: garbageCollect - version - forced - -1
Oct 16, 2019 13:04:25.255 [136524] Debug — Versions: garbage collecting
Oct 16, 2019 13:04:25.257 [136524] Debug — Versions: garbage collected in 0.0 seconds
Oct 16, 2019 13:04:33.649 [13440] Debug — Auth: authenticated user 1 as rschuet
Oct 16, 2019 13:04:33.649 [13436] Debug — Auth: authenticated user 1 as rschuet
Oct 16, 2019 13:04:33.649 [140840] Debug — Request: [137.48.184.218:11489 (WAN)] GET /media/subscriptions/scheduled (8 live) TLS GZIP Signed-in Token (rschuet)
Oct 16, 2019 13:04:33.650 [139652] Debug — Request: [137.48.184.218:11490 (WAN)] GET /media/subscriptions (8 live) TLS GZIP Signed-in Token (rschuet)
Oct 16, 2019 13:04:33.656 [13440] Debug — Completed: [137.48.184.218:11490] 200 GET /media/subscriptions (8 live) TLS GZIP 5ms 1564 bytes (pipelined: 1)
Oct 16, 2019 13:04:33.658 [13440] Debug — Completed: [137.48.184.218:11489] 200 GET /media/subscriptions/scheduled (8 live) TLS GZIP 8ms 5588 bytes (pipelined: 1)
2

Try setting it to “new and repeat airings” instead.
It might also take a while until the record schedule is rescanned. Give it a few minutes.

3

This. I’ve seen odd things happen right after creating a new recording schedule that later clear up with no intervention from me.

4

Well, I waited for the schedule to refresh, and got this. So you see that the recording is now scheduled (twice) for tonight, but recordings for later in the week are missing, even though the show will be on that night.

image
image1030×486 133 KB

Again, looking at the Guide shows the recording symbol on future events, but they do not show up on the recording schedule. I’ve tried waiting to see if they would record, or show up on the schedule later, but they do not.

image
image1552×322 146 KB

5

I think this is due to weaknesses in the EPG. Plex thinks the airings on the following days are just re-runs of the same “episode”. So it doesn’t re-schedule it.
This needs to get corrected at the EPG, I think.

Will try to find more info about it.

2 Likes
6

Thanks for looking into it. Something must have changed in the EPG, because a few months ago it was working fine. I rarely watched the recordings, just had them saved in case I wanted to watch them. That’s why I didn’t notice right away when it stopped working.

7

@rschuet can I please have your zip code and the name of your lineup? You can find the lineup name at the top of the Live TV & DVR settings page in Web.

8

Zip: 68046
Lineup: Broadcast TV Omaha OTA Broadcast

9

Same issue here in Montréal QC: two days ago everything ways fine.
ENP—Broadcast TV - Canada Montréal OTA Broadcast
Postal codeH8T 3K4

Now the DVR schedule is empty, although I have tons of recordings lined up and the TV guide is full.

I tried programming a show through the TV guide: shows up as “Recording” in the TV guide, appears in the Recording priority with “No upcoming airings” (this specific one used to appear all over as those were re-runs that we’re not recorded/part of my PMS library).

Tried again for another show which is definitely not a re-run: that one shows up in the DVR schedule.

Sounds like something is wrong when programming shows that are not “new airings”. “New and repeat airings” does not work. I have about 100 shows programmed across seasons. They all used to work and now poof nothing left.

10

Searching for shows to be recorded is also empty, so the only way to schedule a recording is on the TV guide + if it’s strictly a new airing.

11

Exact same thing here here in Trois-Rivières, QC

12

ENP—Broadcast TV - Canada Trois-Rivieres OTA Broadcast
G8t7c7

14

I have the same issue, If I record all new and repeat airings of College Football across all OTA channels only one gets scheduled. All others show in the EPG as “Recording Scheduled” but the DVR only has one entry. BTW all games show as New with teams TBD.

15

Now I get this on some days. In the end, still nothing is being recorded, even if it shows up as an upcoming recording 10 times.

image
image370×1353 167 KB

16

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