Plex DVR not recording

Plex Media Server Desktops & Laptops
,
41

@sa2000 said:

@raindeer said:
I have had no recording work for the past few weeks, PlexTranscoder.exe keeps crashing and I think this might be the reason? I too get 100% recording complete. I have tried setting it to transcode on the fly and without this option. I can upload any logs if you think more will help you diagnose the issue?

Your last post was in April here http://forums.plex.tv/discussion/267921/plex-cant-record-tv
Suggest you continue with a fresh post in that thread with the zipped full logs requested back in April

I can do, but this is a different problem to the one in that thread. I now have the 100% problem as is mentioned in this thread?

42

@raindeer said:

@sa2000 said:

@raindeer said:
I have had no recording work for the past few weeks, PlexTranscoder.exe keeps crashing and I think this might be the reason? I too get 100% recording complete. I have tried setting it to transcode on the fly and without this option. I can upload any logs if you think more will help you diagnose the issue?

Your last post was in April here http://forums.plex.tv/discussion/267921/plex-cant-record-tv
Suggest you continue with a fresh post in that thread with the zipped full logs requested back in April

I can do, but this is a different problem to the one in that thread. I now have the 100% problem as is mentioned in this thread?

The one here is about recording radio channels and I do not need any logs for that - a fix will get released

43

Just chiming in that I have the same problem of shows recording to the ā€œgrabā€ folder, but never being moved to the library after that. Recording to a synology NAS with permissions granted to the shared folders.

44

Same here. This thread helped me diagnose that shows all shows are indeed being recorded to the ā€˜.grabā€™ folder, but some are not being moved into the TV Shows library. Some are some arenā€™t so trying to troubleshoot that now.

45

Logs with debugging on to capture the time of the problem would be starting point

46

Iā€™m in the same boat as all you guys. Plex pass recording using HDhomerun not working anymore. Waiting for the fix and in the mean time switching recording back to DVBlink.
Rob

47

A fix would be possible once a problem is investigated.

Please ensure that you are using the current release - which is now 1.9.7.4460 and see the following support articles for diagnostics to get the problem investigated

https://support.plex.tv/hc/en-us/articles/201643703-Reporting-issues-with-Plex-Media-Server
https://support.plex.tv/hc/en-us/articles/200250417-Plex-Media-Server-Log-Files

48

Having the same problem as others: recording complete but no playable recordings in designated folder.

Which Mac DVR App is Better: EyeTV vs Plex with HDHomeRun? See imore.com/plex-vs-eyetv-which-mac-dvr-app-best. Definitely EyeTV for nowā€” though as an Elgato customer for a decade and a half, I have no confidence in GeniaTech going forward.

Plex Media Server on a Mac mini and on a couple of OS6 ReadyNASes is good for playback of EyeTV recordings locally and remotely, but paired with an HDHomeRun Extend acquired recently for this purpose, completely unreliable as a DVR.

49

I am having a different issue, but perhaps relatedā€¦ I am seeing episodes being deleted even though they are unwatched and set to ā€œall episodesā€ and ā€œnever deleteā€:

Dec 12, 2017 18:04:05.328 [0x7fc81dffb700] DEBUG - DVR:Grabber: Operation for Law & Order - E8 - Angel completed with status complete (Success)
Dec 12, 2017 18:04:05.328 [0x7fc805ffb700] DEBUG - Garbage Collector: Deleting expired unwatched content for Law & Order (policy: -7 before 2017-12-05)
Dec 12, 2017 18:04:05.328 [0x7fc81dffb700] DEBUG - Activity: Ended activity 00bb8df0-2290-4c09-8c3d-dc914e9d6eda.

The option in the Advanced dialogue box specifically says:

DELETE EPISODES AFTER WATCHING

Choose how quickly episodes are removed after the server admin has watched them.

Not only is it set to ā€œNEVER,ā€ but even if it werenā€™t, the log message above says ā€œI am deleting UNWATCHED content.ā€ The dialogue box says ā€œafter the server admin has watched them.ā€

What am I missing? I am running server version 1.10.0.4523-648bc61d4 on Linux (Ubuntu).

50

@zooperific said:
I am having a different issue, but perhaps relatedā€¦ I am seeing episodes being deleted even though they are unwatched and set to ā€œall episodesā€ and ā€œnever deleteā€:

Dec 12, 2017 18:04:05.328 [0x7fc81dffb700] DEBUG - DVR:Grabber: Operation for Law & Order - E8 - Angel completed with status complete (Success)
Dec 12, 2017 18:04:05.328 [0x7fc805ffb700] DEBUG - Garbage Collector: Deleting expired unwatched content for Law & Order (policy: -7 before 2017-12-05)
Dec 12, 2017 18:04:05.328 [0x7fc81dffb700] DEBUG - Activity: Ended activity 00bb8df0-2290-4c09-8c3d-dc914e9d6eda.

The option in the Advanced dialogue box specifically says:

DELETE EPISODES AFTER WATCHING

Choose how quickly episodes are removed after the server admin has watched them.

Not only is it set to ā€œNEVER,ā€ but even if it werenā€™t, the log message above says ā€œI am deleting UNWATCHED content.ā€ The dialogue box says ā€œafter the server admin has watched them.ā€

What am I missing? I am running server version 1.10.0.4523-648bc61d4 on Linux (Ubuntu).

Have a look at this support article https://support.plex.tv/hc/en-us/articles/226074728-Setting-Up-Recordings and specifically to the Keep setting. There is a note about how the Keep Episodes from the past x days is used

51

Thanks sa2000ā€¦ It actually turned out to be one of those scenarios where I had to copy a file and rename it to match the ā€œfavoriteā€ and then go edit the preferences for that favoriteā€¦ I assume the plex team is working on that in the form of a ā€œfavorite managerā€ or similar, as that methodology is not idea.

I have a follow up question regarding the recording logic in general, the logs are below as a referenceā€¦ I am pretty sure that plex is recording shows first and then checking to see if they are already ā€œwatchedā€ secondā€¦ that seems odd to me, and if so is there a specific reason for that logic? Why tie up a tuner for something that you are going to delete? thx

Dec 14, 2017 07:10:59.995 [0x7f58f47ff700] DEBUG - DVR:Grabber: Scheduling 'Law & Order - E22 - Attorney Client' for assimilation.
Dec 14, 2017 07:10:59.998 [0x7f58a7bfa700] DEBUG - DVR:Subscription: Resolved subscription to library item 'Law & Order' (77997)
Dec 14, 2017 07:11:00.000 [0x7f58a7bfa700] DEBUG - There were 1 top-level paths for Law & Order.
Dec 14, 2017 07:11:00.000 [0x7f58a7bfa700] DEBUG - DVR:Grabber: Postprocessing, we're going to put `Law & Order - E22 - Attorney Client` in `/var/media/tv/plex-dvr/Law & Order (1990)/Season 12/Law & Order (1990) - S12E22 - Attorney Client.ts`
Dec 14, 2017 07:11:00.001 [0x7f58a7bfa700] DEBUG - DVR:Grabber: Moving /var/media/tv/plex-dvr/.grab/775e224ced53e8f7eb19418a832d5c50d746159a/Law & Order (1990) - S12E22 - Attorney Client.ts to /var/media/tv/plex-dvr/Law & Order (1990)/Season 12/Law & Order (1990) - S12E22 - Attorney Client.ts
Dec 14, 2017 07:11:00.001 [0x7f58a7bfa700] DEBUG - DVR:Subscription: Resolved subscription to library item 'Law & Order' (77997)
Dec 14, 2017 07:11:02.681 [0x7f58a7bfa700] DEBUG - DVR:Assimilator: Refreshing 'Law & Order - E22 - Attorney Client' (guid: com.plexapp.agents.thetvdb://72368?lang=en) with force: 1
Dec 14, 2017 07:11:02.681 [0x7f58a7bfa700] DEBUG - Refreshing metadata for 'Law & Order' (agent: com.plexapp.agents.thetvdb)
Dec 14, 2017 07:11:02.899 [0x7f59007ff700] DEBUG - Garbage Collector: Deleting expired unwatched content for Law & Order (policy: 5)
Dec 14, 2017 07:11:02.899 [0x7f58a7bfa700] DEBUG - DVR:Grabber: Operation for Law & Order - E22 - Attorney Client completed with status complete (Success)
Dec 14, 2017 07:11:03.466 [0x7f59007ff700] DEBUG - Garbage Collector: Deleting expired watched content for Law & Order (policy: 1)
Dec 14, 2017 07:11:04.694 [0x7f58a8bfc700] DEBUG - DVR:Subscription: Resolved subscription to library item 'Law & Order' (77997)
Dec 14, 2017 07:11:04.703 [0x7f58a8bfc700] DEBUG - There were 0 top-level paths for Law & Order.
52

@zooperific said:
Thanks sa2000ā€¦ It actually turned out to be one of those scenarios where I had to copy a file and rename it to match the ā€œfavoriteā€ and then go edit the preferences for that favoriteā€¦ I assume the plex team is working on that in the form of a ā€œfavorite managerā€ or similar, as that methodology is not idea.

I have a follow up question regarding the recording logic in general, the logs are below as a referenceā€¦ I am pretty sure that plex is recording shows first and then checking to see if they are already ā€œwatchedā€ secondā€¦ that seems odd to me, and if so is there a specific reason for that logic? Why tie up a tuner for something that you are going to delete? thx

Dec 14, 2017 07:10:59.995 [0x7f58f47ff700] DEBUG - DVR:Grabber: Scheduling 'Law & Order - E22 - Attorney Client' for assimilation.
Dec 14, 2017 07:10:59.998 [0x7f58a7bfa700] DEBUG - DVR:Subscription: Resolved subscription to library item 'Law & Order' (77997)
Dec 14, 2017 07:11:00.000 [0x7f58a7bfa700] DEBUG - There were 1 top-level paths for Law & Order.
Dec 14, 2017 07:11:00.000 [0x7f58a7bfa700] DEBUG - DVR:Grabber: Postprocessing, we're going to put `Law & Order - E22 - Attorney Client` in `/var/media/tv/plex-dvr/Law & Order (1990)/Season 12/Law & Order (1990) - S12E22 - Attorney Client.ts`
Dec 14, 2017 07:11:00.001 [0x7f58a7bfa700] DEBUG - DVR:Grabber: Moving /var/media/tv/plex-dvr/.grab/775e224ced53e8f7eb19418a832d5c50d746159a/Law & Order (1990) - S12E22 - Attorney Client.ts to /var/media/tv/plex-dvr/Law & Order (1990)/Season 12/Law & Order (1990) - S12E22 - Attorney Client.ts
Dec 14, 2017 07:11:00.001 [0x7f58a7bfa700] DEBUG - DVR:Subscription: Resolved subscription to library item 'Law & Order' (77997)
Dec 14, 2017 07:11:02.681 [0x7f58a7bfa700] DEBUG - DVR:Assimilator: Refreshing 'Law & Order - E22 - Attorney Client' (guid: com.plexapp.agents.thetvdb://72368?lang=en) with force: 1
Dec 14, 2017 07:11:02.681 [0x7f58a7bfa700] DEBUG - Refreshing metadata for 'Law & Order' (agent: com.plexapp.agents.thetvdb)
Dec 14, 2017 07:11:02.899 [0x7f59007ff700] DEBUG - Garbage Collector: Deleting expired unwatched content for Law & Order (policy: 5)
Dec 14, 2017 07:11:02.899 [0x7f58a7bfa700] DEBUG - DVR:Grabber: Operation for Law & Order - E22 - Attorney Client completed with status complete (Success)
Dec 14, 2017 07:11:03.466 [0x7f59007ff700] DEBUG - Garbage Collector: Deleting expired watched content for Law & Order (policy: 1)
Dec 14, 2017 07:11:04.694 [0x7f58a8bfc700] DEBUG - DVR:Subscription: Resolved subscription to library item 'Law & Order' (77997)
Dec 14, 2017 07:11:04.703 [0x7f58a8bfc700] DEBUG - There were 0 top-level paths for Law & Order.

Thanks for flagging this. Yes it does not make sense to record if we end up purging at the end of recording.
I have discussed this with the team

53

I have the same problem. I am using an HDHomeRun tuner and Plex running on a Synology. Last night, I scheduled Victoria to record at 9. Before the recording, it showed up in the Recording Schedule. This morning, the show was missing from my recorded shows AND the entry in last nightā€™s Recording Schedule was missing.

The show I was recording was Victoria. Here is the log file ā€¦

Jan 14, 2018 21:30:46.462 [0xf1223b40] DEBUG - DVR:Subscription: Failed to resolve subscription for 'guid: com.gracenote.onconnect://show/13194214 thumb: http://tmsimg.plex.tv/assets/p13194214_b_v5_ac.jpg title: Victoria type: 2 year: 2016' to library item
Jan 14, 2018 21:30:46.556 [0xd4bffb40] DEBUG - DVR:Subscription: Failed to resolve subscription for 'guid: com.gracenote.onconnect://show/13194214 thumb: http://tmsimg.plex.tv/assets/p13194214_b_v5_ac.jpg title: Victoria type: 2 year: 2016' to library item
Jan 14, 2018 21:30:46.672 [0xf1223b40] DEBUG - There were 0 top-level paths for Victoria.
Jan 14, 2018 21:30:46.672 [0xd4bffb40] DEBUG - There were 0 top-level paths for Victoria.
Jan 14, 2018 21:30:46.706 [0xd4bffb40] DEBUG - DVR:Subscription: No match in the library for 'Victoria - A Soldier's Daughter; The Green-Eyed Monster', grabbing.
Jan 14, 2018 21:30:46.706 [0xd4bffb40] DEBUG - DVR:Subscription: No match in the library for 'Victoria - Warp and Weft; The Sins of the Father', grabbing.
Jan 14, 2018 21:30:46.706 [0xf1223b40] DEBUG - DVR:Subscription: No match in the library for 'Victoria - A Soldier's Daughter; The Green-Eyed Monster', grabbing.
Jan 14, 2018 21:30:46.706 [0xf1223b40] DEBUG - DVR:Subscription: No match in the library for 'Victoria - Warp and Weft; The Sins of the Father', grabbing.
Jan 14, 2018 21:30:53.631 [0xf1223b40] DEBUG - DVR:Schedule: Attempting to schedule the item 'Victoria - A Soldier's Daughter; The Green-Eyed Monster'.
Jan 14, 2018 21:30:53.631 [0xf1223b40] DEBUG - DVR:Grabber: Starting grab immediately for 'Victoria - A Soldier's Daughter; The Green-Eyed Monster' and we're 1913 seconds in already.
Jan 14, 2018 21:30:53.632 [0xf1223b40] DEBUG - DVR:Schedule: Attempting to schedule the item 'Victoria - Warp and Weft; The Sins of the Father'.
Jan 14, 2018 21:30:53.634 [0xf0f35b40] DEBUG - DVR:Grabber: Starting operation for 'Victoria - A Soldier's Daughter; The Green-Eyed Monster'.
Jan 14, 2018 21:30:53.637 [0xf1223b40] DEBUG - DVR:Grabber: Scheduling 'Victoria - Warp and Weft; The Sins of the Father' for timed grab at 2018-01-21 20:59:00 (in 602887 seconds)
Jan 14, 2018 21:30:53.645 [0xf0f35b40] DEBUG - DVR:Subscription: Failed to resolve subscription for 'guid: com.gracenote.onconnect://show/13194214 thumb: http://tmsimg.plex.tv/assets/p13194214_b_v5_ac.jpg title: Victoria type: 2 year: 2016' to library item
Jan 14, 2018 21:30:53.665 [0xf0f35b40] DEBUG - DVR:Grabber: Setting timer to stop grab of 'Victoria - A Soldier's Daughter; The Green-Eyed Monster' in 5407 seconds.
Jan 14, 2018 21:30:53.666 [0xf0f35b40] DEBUG - DVR:Grabber: Operation for Victoria - A Soldier's Daughter; The Green-Eyed Monster completed with status error (The recording was aborted)
Jan 14, 2018 21:30:53.919 [0xd4bffb40] DEBUG - DVR:Schedule: Attempting to schedule the item 'Victoria - A Soldier's Daughter; The Green-Eyed Monster'.
Jan 14, 2018 21:30:53.920 [0xd4bffb40] DEBUG - DVR:Grabber: Setting timer to stop grab of 'Victoria - A Soldier's Daughter; The Green-Eyed Monster' in 5407 seconds.
Jan 14, 2018 21:30:53.920 [0xd4bffb40] DEBUG - DVR:Schedule: Attempting to schedule the item 'Victoria - Warp and Weft; The Sins of the Father'.
Jan 14, 2018 21:30:53.920 [0xd4bffb40] DEBUG - DVR:Grabber: Scheduling 'Victoria - Warp and Weft; The Sins of the Father' for timed grab at 2018-01-21 20:59:00 (in 602887 seconds)
Jan 14, 2018 21:31:24.630 [0xf07ffb40] DEBUG - DVR:Subscription: Failed to resolve subscription for 'guid: com.gracenote.onconnect://show/13194214 thumb: http://tmsimg.plex.tv/assets/p13194214_b_v5_ac.jpg title: Victoria type: 2 year: 2016' to library item
Jan 14, 2018 21:31:24.647 [0xf07ffb40] DEBUG - There were 0 top-level paths for Victoria.
Jan 14, 2018 21:31:24.676 [0xf07ffb40] DEBUG - DVR:Subscription: No match in the library for 'Victoria - A Soldier's Daughter; The Green-Eyed Monster', grabbing.
Jan 14, 2018 21:31:24.676 [0xf07ffb40] DEBUG - DVR:Subscription: No match in the library for 'Victoria - Warp and Weft; The Sins of the Father', grabbing.
Jan 14, 2018 21:31:26.279 [0xf07ffb40] DEBUG - DVR:Schedule: Attempting to schedule the item 'Victoria - A Soldier's Daughter; The Green-Eyed Monster'.
Jan 14, 2018 21:31:26.280 [0xf07ffb40] DEBUG - DVR:Grabber: Starting grab immediately for 'Victoria - A Soldier's Daughter; The Green-Eyed Monster' and we're 1946 seconds in already.
Jan 14, 2018 21:31:26.283 [0xe8e23b40] DEBUG - DVR:Grabber: Starting operation for 'Victoria - A Soldier's Daughter; The Green-Eyed Monster'.
Jan 14, 2018 21:31:26.285 [0xf07ffb40] DEBUG - DVR:Schedule: Attempting to schedule the item 'Victoria - Warp and Weft; The Sins of the Father'.
Jan 14, 2018 21:31:26.286 [0xf07ffb40] DEBUG - DVR:Grabber: Scheduling 'Victoria - Warp and Weft; The Sins of the Father' for timed grab at 2018-01-21 20:59:00 (in 602854 seconds)
Jan 14, 2018 21:31:26.293 [0xe8e23b40] DEBUG - DVR:Subscription: Failed to resolve subscription for 'guid: com.gracenote.onconnect://show/13194214 thumb: http://tmsimg.plex.tv/assets/p13194214_b_v5_ac.jpg title: Victoria type: 2 year: 2016' to library item
Jan 14, 2018 21:31:26.313 [0xe8e23b40] DEBUG - DVR:Grabber: Setting timer to stop grab of 'Victoria - A Soldier's Daughter; The Green-Eyed Monster' in 5374 seconds.
Jan 14, 2018 21:31:26.314 [0xe8e23b40] DEBUG - DVR:Grabber: Operation for Victoria - A Soldier's Daughter; The Green-Eyed Monster completed with status error (The recording was aborted)
Jan 14, 2018 23:01:00.314 [0xf1fffb40] DEBUG - DVR:Grabber: Cancelling operation for 'Victoria - A Soldier's Daughter; The Green-Eyed Monster' with status complete.
Jan 15, 2018 03:07:30.007 [0xefc47b40] DEBUG - DVR:Subscription: Failed to resolve subscription for 'guid: com.gracenote.onconnect://show/13194214 thumb: http://tmsimg.plex.tv/assets/p13194214_b_v5_ac.jpg title: Victoria type: 2 year: 2016' to library item
Jan 15, 2018 03:07:30.023 [0xefc47b40] DEBUG - There were 0 top-level paths for Victoria.
Jan 15, 2018 03:07:30.052 [0xefc47b40] DEBUG - DVR:Subscription: We want a premiere, and 'Victoria - A Soldier's Daughter; The Green-Eyed Monster' is an old airing, ignoring.
Jan 15, 2018 03:07:30.052 [0xefc47b40] DEBUG - DVR:Subscription: No match in the library for 'Victoria - Warp and Weft; The Sins of the Father', grabbing.
Jan 15, 2018 03:07:31.747 [0xefc47b40] DEBUG - DVR:Schedule: Attempting to schedule the item 'Victoria - Warp and Weft; The Sins of the Father'.
Jan 15, 2018 03:07:31.747 [0xefc47b40] DEBUG - DVR:Grabber: Scheduling 'Victoria - Warp and Weft; The Sins of the Father' for timed grab at 2018-01-21 20:59:00 (in 582689 seconds)
Jan 15, 2018 10:37:05.499 [0xe93ffb40] DEBUG - DVR:Subscription: Failed to resolve subscription for 'guid: com.gracenote.onconnect://show/13194214 thumb: http://tmsimg.plex.tv/assets/p13194214_b_v5_ac.jpg title: Victoria type: 2 year: 2016' to library item
Jan 15, 2018 10:37:05.529 [0xe93ffb40] DEBUG - There were 0 top-level paths for Victoria.
Jan 15, 2018 10:37:05.572 [0xe93ffb40] DEBUG - DVR:Subscription: We want a premiere, and 'Victoria - A Soldier's Daughter; The Green-Eyed Monster' is an old airing, ignoring.
Jan 15, 2018 10:37:05.572 [0xe93ffb40] DEBUG - DVR:Subscription: No match in the library for 'Victoria - Warp and Weft; The Sins of the Father', grabbing.
Jan 15, 2018 10:37:10.302 [0xe93ffb40] DEBUG - DVR:Schedule: Attempting to schedule the item 'Victoria - Warp and Weft; The Sins of the Father'.
Jan 15, 2018 10:37:10.303 [0xe93ffb40] DEBUG - DVR:Grabber: Scheduling 'Victoria - Warp and Weft; The Sins of the Father' for timed grab at 2018-01-21 20:59:00 (in 555710 seconds)
Jan 15, 2018 10:37:11.146 [0xe8b35b40] DEBUG - DVR:Subscription: Failed to resolve subscription for 'guid: com.gracenote.onconnect://show/13194214 thumb: http://tmsimg.plex.tv/assets/p13194214_b_v5_ac.jpg title: Victoria type: 2 year: 2016' to library item
Jan 15, 2018 10:37:11.175 [0xe8b35b40] DEBUG - There were 0 top-level paths for Victoria.
Jan 15, 2018 10:37:11.241 [0xe8b35b40] DEBUG - DVR:Subscription: We want a premiere, and 'Victoria - A Soldier's Daughter; The Green-Eyed Monster' is an old airing, ignoring.
Jan 15, 2018 10:37:11.241 [0xe8b35b40] DEBUG - DVR:Subscription: No match in the library for 'Victoria - Warp and Weft; The Sins of the Father', grabbing.
Jan 15, 2018 10:37:13.041 [0xe8b35b40] DEBUG - DVR:Schedule: Attempting to schedule the item 'Victoria - Warp and Weft; The Sins of the Father'.
Jan 15, 2018 10:37:13.041 [0xe8b35b40] DEBUG - DVR:Grabber: Scheduling 'Victoria - Warp and Weft; The Sins of the Father' for timed grab at 2018-01-21 20:59:00 (in 555707 seconds)
Jan 15, 2018 10:38:16.935 [0xf07ffb40] DEBUG - DVR:Subscription: Failed to resolve subscription for 'guid: com.gracenote.onconnect://show/13194214 thumb: http://tmsimg.plex.tv/assets/p13194214_b_v5_ac.jpg title: Victoria type: 2 year: 2016' to library item
Jan 15, 2018 10:43:05.542 [0xefc47b40] DEBUG - DVR:Subscription: Failed to resolve subscription for 'guid: com.gracenote.onconnect://show/13194214 thumb: http://tmsimg.plex.tv/assets/p13194214_b_v5_ac.jpg title: Victoria type: 2 year: 2016' to library item
Jan 15, 2018 10:43:26.345 [0xefc47b40] DEBUG - DVR:Subscription: Failed to resolve subscription for 'guid: com.gracenote.onconnect://show/13194214 thumb: http://tmsimg.plex.tv/assets/p13194214_b_v5_ac.jpg title: Victoria type: 2 year: 2016' to library item
Jan 15, 2018 10:43:27.321 [0xefc47b40] DEBUG - DVR:Subscription: Failed to resolve subscription for 'guid: com.gracenote.onconnect://show/13194214 thumb: http://tmsimg.plex.tv/assets/p13194214_b_v5_ac.jpg title: Victoria type: 2 year: 2016' to library item
Jan 15, 2018 10:43:27.338 [0xefc47b40] DEBUG - There were 0 top-level paths for Victoria.
Jan 15, 2018 10:43:27.370 [0xefc47b40] DEBUG - DVR:Subscription: We want a premiere, and 'Victoria - A Soldier's Daughter; The Green-Eyed Monster' is an old airing, ignoring.
Jan 15, 2018 10:43:27.370 [0xefc47b40] DEBUG - DVR:Subscription: No match in the library for 'Victoria - Warp and Weft; The Sins of the Father', grabbing.
Jan 15, 2018 10:43:27.418 [0xefc47b40] DEBUG - DVR:Schedule: Attempting to schedule the item 'Victoria - Warp and Weft; The Sins of the Father'.
Jan 15, 2018 10:43:27.418 [0xefc47b40] DEBUG - DVR:Grabber: Scheduling 'Victoria - Warp and Weft; The Sins of the Father' for timed grab at 2018-01-21 20:59:00 (in 555333 seconds)

The correct episode was ā€˜A Soldierā€™s Daughter; The Green-Eyed Monsterā€™.

Any clues as to what is going on?

54

@zooperific said:
I am having a different issue, but perhaps relatedā€¦ I am seeing episodes being deleted even though they are unwatched and set to ā€œall episodesā€ and ā€œnever deleteā€

Same issue here, but only for some shows, and this happens once a week for a weekly show.

55

Also having this same problem for several weeks now. None on my shows are recording. Ubuntu Plex Pass PMS Version 1.11.0.4666. Please fix.

56

same issue here. I am running plex in a docker container on Ubuntu. Version 1.10.1.4602

57

Need full logs covering time of recordings and at end of recording to establish why

But it could be retention policy as the ā€˜keep for xx daysā€™ is based on the original airing date

See section ā€˜Keepā€™ on this support article
https://support.plex.tv/articles/226074728-setting-up-recordings/

And for logs
https://support.plex.tv/articles/201643703-reporting-issues-with-plex-media-server/
https://support.plex.tv/articles/200250417-plex-media-server-log-files/

58

Seems quite likely, as the current naming, method, and instantiation is opaque and not ideal. I actually like the concept of ā€œkeep for X days following the recording of a show,ā€ but tying it to original air date is a mistake. Plex has the opportunity to bake in proper logic here, hopefully we will see improvement there as well as transparency in the ordering + UI/logging.

59

Iā€™m having similar issues to the ones described in this thread. Episodes are recording to 100% but most are failing to move for some reason or another. For example, this is what I believe to be the relevant entries from my Plex Media Server logs while recording SNL last night. It aired from 9:29-11:02pm last night and Plex accurately saw the scheduled airtime and began recording, but it never finished, and Iā€™m assuming this is the reason:

Feb 03, 2018 23:52:32.657 [0x14df665fe700] DEBUG - DVR:Schedule: Attempting to schedule the item 'Saturday Night Live - E15 - Natalie Portman; Dua Lipa'.
Feb 03, 2018 23:52:32.663 [0x14df665fe700] DEBUG - DVR:Schedule: Trying airing at 2018-02-03 21:29:00 on channel 13.1.
Feb 03, 2018 23:52:32.663 [0x14df665fe700] DEBUG - DVR:Schedule: Airing was in the past, ignoring.
Feb 03, 2018 23:52:32.663 [0x14df665fe700] DEBUG - DVR:Grabber: Operation for Saturday Night Live - E15 - Natalie Portman; Dua Lipa completed with status error (Cannot record airing in the past)

I left it alone overnight and when I checked on the status in the morning, the filesize of ts file was still continuing to grow (up to 8GB at that point) when I just decided to restart plex to clear it away.

Hereā€™s the rest of the logs: https://hastebin.com/wosugerufu.sql

60

@captainnapalm said:
Iā€™m having similar issues to the ones described in this thread. Episodes are recording to 100% but most are failing to move for some reason or another. For example, this is what I believe to be the relevant entries from my Plex Media Server logs while recording SNL last night. It aired from 9:29-11:02pm last night and Plex accurately saw the scheduled airtime and began recording, but it never finished, and Iā€™m assuming this is the reason:

Feb 03, 2018 23:52:32.657 [0x14df665fe700] DEBUG - DVR:Schedule: Attempting to schedule the item 'Saturday Night Live - E15 - Natalie Portman; Dua Lipa'.
Feb 03, 2018 23:52:32.663 [0x14df665fe700] DEBUG - DVR:Schedule: Trying airing at 2018-02-03 21:29:00 on channel 13.1.
Feb 03, 2018 23:52:32.663 [0x14df665fe700] DEBUG - DVR:Schedule: Airing was in the past, ignoring.
Feb 03, 2018 23:52:32.663 [0x14df665fe700] DEBUG - DVR:Grabber: Operation for Saturday Night Live - E15 - Natalie Portman; Dua Lipa completed with status error (Cannot record airing in the past)

I left it alone overnight and when I checked on the status in the morning, the filesize of ts file was still continuing to grow (up to 8GB at that point) when I just decided to restart plex to clear it away.

Hereā€™s the rest of the logs: https://hastebin.com/wosugerufu.sql

would need logs covering the time when the recording was to start

The download logs function is provided so we can get all the log files - there would be 6 and may be they would cover the earlier time

If the 6 logs are not sufficient - can look into editing the preferences to add a LogNumFiles entry
See https://support.plex.tv/articles/201105343-advanced-hidden-server-settings/

Also from log snippets one cannot confirm what version was in use

So please see if the logs zip goes back to before 11:02 pm - if you captured it
see https://support.plex.tv/articles/200250417-plex-media-server-log-files/

If not, we have to wait for next occurrence - perhaps with number of log files increased