After running a couple of days it’s back to the same issues and it’s not cleaning up the .grab folder by itself. This is getting very frustrating.
Latest log:
Plex Media Server Logs_2025-05-16_11-09-44.zip (5.1 MB)
The Plex Server Logs in the 2025-05-15 zip cover from 5/14/25 18:35 until 5/15/25 10:51. I’m only seeing one recording fail during that time period, which was “All of Us - E18”. This was one where the previous recording (“All of Us - S17”) on the same channel likely has a couple minutes of “extra time”, so it should be using the same tuner for both recordings, but when the second recording tried to start on the same channel it failed with a “Stream map ‘0:0’ matches no streams.” This appears to be FFMPEG returning that it did not find the specified video stream.
Did you see other failures during that time period? This was a search for “Recording failed” in that log set - with over 50MB of text files you have to do keyword searches, or know specific shows and/or times of failures.
Interestingly, the one decrepit directory that isn’t getting cleaned up in the 2025-05-16 logs is from this one failure. Note logs specifying “S:\TV Shows.grab\25db9639f0293be788314fc7a55c229cf6c03dc2-88d24317a31be0ffff5f4096e0d9ab12274a1f3c”
Recording start from 2025-05-15
May 15, 2025 10:29:00.604 [10772] DEBUG - [Req#f3cbe/Grabber/25db9639f0293be788314fc7a55c229cf6c03dc2] Starting operation for 'All of Us - E18 - A Family Affair' on channel 5fc76c796b022a002d86984e-5fc705f1678e02002eccfe96.
May 15, 2025 10:29:00.604 [10772] DEBUG - [Req#f3cbe/Grabber/25db9639f0293be788314fc7a55c229cf6c03dc2] Activity: registered new activity 3e55d3a6-5f32-4180-aa0c-a6e8a3556046 - "Recording"
May 15, 2025 10:29:00.604 [10772] DEBUG - [Req#f3cbe/Grabber/25db9639f0293be788314fc7a55c229cf6c03dc2] Grabber: Remote starting a media grab on device device://tv.plex.grabbers.tunerservice/dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6b180070%26rev_04%236%262a91e53a%260%26001000e8%23 (Hauppauge WinTV-quadHD, supported) tuner 0.
May 15, 2025 10:29:00.606 [10772] DEBUG - [Req#f3cbe/Grabber/25db9639f0293be788314fc7a55c229cf6c03dc2] Subscription: No existing library item episode 'grandparentGuid: plex://show/5fc69824c40548002d481b5b grandparentThumb: https://metadata-static.plex.tv/e/gracenote/e250ad7cdb5a014235c374befb37d0d6.jpg grandparentTitle: All of Us grandparentYear: 2003 guid: plex://episode/5fc6efcda62668002dccbd1b index: 18 originallyAvailableAt: 2004-03-02 parentGuid: plex://season/5fc69824c40548002d481b5b/1 parentIndex: 1 parentTitle: Season 1 ratingKey: plex%3A%2F%2Fepisode%2F5fc6efcda62668002dccbd1b thumb: https://metadata-static.plex.tv/c/gracenote/cb26bea88a7bedebde3a2f09c8436413.jpg title: A Family Affair type: 4 year: 2004'
May 15, 2025 10:29:00.607 [14868] DEBUG - [Req#f3cbe/Grabber/b126feec8eb23ae550b7343e154b0cb47f91a585] Subscription: No existing library item episode 'grandparentGuid: plex://show/5fc6ae891f0a12002eb3cf06 grandparentThumb: https://metadata-static.plex.tv/0/gracenote/0507dced64167399c0c6640c8553f3b9.jpg grandparentTitle: The Lucy Show grandparentYear: 1962 guid: plex://episode/5fc6fef97c6557002ee0f66b index: 20 originallyAvailableAt: 1968-02-05 parentGuid: plex://season/5fc6ae891f0a12002eb3cf06/6 parentIndex: 6 parentTitle: Season 6 ratingKey: plex%3A%2F%2Fepisode%2F5fc6fef97c6557002ee0f66b title: Lucy and Phil Harris type: 4 year: 1968'
May 15, 2025 10:29:00.607 [14868] DEBUG - [Req#f3cbe/Grabber/b126feec8eb23ae550b7343e154b0cb47f91a585] Grabber: checking for 9830 MB of available disk space in grab path and 400 MB in the transcode cache path
May 15, 2025 10:29:00.607 [10772] DEBUG - [Req#f3cbe/Grabber/25db9639f0293be788314fc7a55c229cf6c03dc2] Grabber: checking for 9830 MB of available disk space in grab path and 400 MB in the transcode cache path
May 15, 2025 10:29:00.620 [13356] DEBUG - [HttpClient/HCl#3567] HTTP/1.1 (0.8s) 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2612867a92%260%26000800e8%23 (reused)
May 15, 2025 10:29:00.621 [3668] DEBUG - [Req#f3cbe/Grabber/b126feec8eb23ae550b7343e154b0cb47f91a585/HCl#3568] HTTP requesting GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6b180070%26rev_04%236%262a91e53a%260%26001000e8%23
May 15, 2025 10:29:00.628 [296] DEBUG - [Req#f6d34/Transcode/5a1f3e7d-215b-435b-9043-c6d0a6337cea/765709d6-8517-44c1-b45f-7dc21d6c723e] Transcoder segment range: 0 - 3584 (3584)
May 15, 2025 10:29:00.643 [296] DEBUG - [Req#f6d47/Transcode/5a1f3e7d-215b-435b-9043-c6d0a6337cea/765709d6-8517-44c1-b45f-7dc21d6c723e] Transcoder segment range: 0 - 3585 (3584)
May 15, 2025 10:29:00.648 [10772] DEBUG - [Req#f3cbe/Grabber/25db9639f0293be788314fc7a55c229cf6c03dc2] Grabber: Saving recording to "S:\TV Shows\.grab\25db9639f0293be788314fc7a55c229cf6c03dc2-88d24317a31be0ffff5f4096e0d9ab12274a1f3c"
Decrepit log items 2025-05-16
grep -B 1 "decrepit" *.*
Plex Media Server.1.log-May 16, 2025 04:01:06.586 [12020] DEBUG - Grabber: Deleting "S:\TV Shows\.grab\25db9639f0293be788314fc7a55c229cf6c03dc2-88d24317a31be0ffff5f4096e0d9ab12274a1f3c".
Plex Media Server.1.log:May 16, 2025 04:01:06.611 [12020] DEBUG - Grabber: Cleaned up 1 decrepit directories in 0.0 sec.
--
Plex Media Server.1.log-May 16, 2025 06:32:08.715 [11948] DEBUG - Grabber: Deleting "S:\TV Shows\.grab\25db9639f0293be788314fc7a55c229cf6c03dc2-88d24317a31be0ffff5f4096e0d9ab12274a1f3c".
Plex Media Server.1.log:May 16, 2025 06:32:09.095 [11948] DEBUG - Grabber: Cleaned up 1 decrepit directories in 0.0 sec.
--
Plex Media Server.1.log-May 16, 2025 06:32:13.564 [11516] DEBUG - Grabber: Deleting "S:\TV Shows\.grab\25db9639f0293be788314fc7a55c229cf6c03dc2-88d24317a31be0ffff5f4096e0d9ab12274a1f3c".
Plex Media Server.1.log:May 16, 2025 06:32:13.588 [11516] DEBUG - Grabber: Cleaned up 1 decrepit directories in 0.0 sec.
--
Plex Media Server.2.log-May 16, 2025 00:02:07.095 [1744] DEBUG - Grabber: Deleting "S:\TV Shows\.grab\25db9639f0293be788314fc7a55c229cf6c03dc2-88d24317a31be0ffff5f4096e0d9ab12274a1f3c".
Plex Media Server.2.log:May 16, 2025 00:02:07.144 [1744] DEBUG - Grabber: Cleaned up 1 decrepit directories in 0.0 sec.
--
Plex Media Server.2.log-May 16, 2025 00:02:10.867 [11108] DEBUG - Grabber: Deleting "S:\TV Shows\.grab\25db9639f0293be788314fc7a55c229cf6c03dc2-88d24317a31be0ffff5f4096e0d9ab12274a1f3c".
Plex Media Server.2.log:May 16, 2025 00:02:10.991 [11108] DEBUG - Grabber: Cleaned up 1 decrepit directories in 0.0 sec.
--
Plex Media Server.3.log-May 15, 2025 19:01:06.195 [2200] DEBUG - Grabber: Deleting "S:\TV Shows\.grab\25db9639f0293be788314fc7a55c229cf6c03dc2-88d24317a31be0ffff5f4096e0d9ab12274a1f3c".
Plex Media Server.3.log:May 15, 2025 19:01:06.218 [2200] DEBUG - Grabber: Cleaned up 1 decrepit directories in 0.0 sec.
--
Plex Media Server.3.log-May 15, 2025 19:01:10.522 [13392] DEBUG - Grabber: Deleting "S:\TV Shows\.grab\25db9639f0293be788314fc7a55c229cf6c03dc2-88d24317a31be0ffff5f4096e0d9ab12274a1f3c".
Plex Media Server.3.log:May 15, 2025 19:01:10.542 [13392] DEBUG - Grabber: Cleaned up 1 decrepit directories in 0.0 sec.
--
Plex Media Server.log-May 16, 2025 09:16:46.171 [5824] DEBUG - Grabber: Deleting "S:\TV Shows\.grab\25db9639f0293be788314fc7a55c229cf6c03dc2-88d24317a31be0ffff5f4096e0d9ab12274a1f3c".
Plex Media Server.log:May 16, 2025 09:16:46.198 [5824] DEBUG - Grabber: Cleaned up 1 decrepit directories in 0.0 sec.
In my mind there are now ̶t̶w̶o̶ three separate but related issues:
- Recording failures.
- Grab directories from recording failures not being removed.
- Postprocessing errors.
Do you have any extra space on your C: drive for a test that may give us additional insight? The test would be to create a new empty TV Show library on your C: drive, and test some recordings without any extra recording “time before” or “time after”. If that should work reliably then try adding “time before” and “time after”. If either or both of these work reliably then we have a better idea where to focus.
I can do that, I’ll run the testing and update later.
The test seemed to go OK, although recordings came out at 29 minutes instead of the expected 30. Here’s the log:
Plex Media Server Logs_2025-05-18_09-13-37.zip (5.4 MB)
For the recordings that came out as 29 minutes, if you play them what does it actually show the length? It isn’t uncommon for me to see an hour recording reporting as 59 minutes from the episode title screen, but if I play it the actual length will be something like 59 minutes and 57 seconds. Same for 30 minutes shows that will show 29 minutes on the episode title screen, but will actually be 29 minutes 57 seconds. For me, that falls in the acceptable tolerance range to be considered a success.
The positive from these logs is that I see no recording failures at all. I see 4 successes for recordings going to your C: drive library, but I also see 4 success for recordings going to your S: drive library. I do see some credit detection failures that are all for the S: drive library, but these could be partials from previous recording failures.
I see a successful “overlap” recording to your C: drive library where “My Three Sons - E5” started on 5/17 at 13:59 on the same channel where Brady Bunch S04E17 was already recording, and Brady Bunch didn’t finish until 14:02.
I don’t think this is definitive, but since we haven’t seen any failures yet to the C: drive library, it could indicate that it is somehow associated with your S: NAS drive.
As shot in the dark - One possibility is bandwidth limitation or wifi instability. Are both your Plex Server and your NAS device connected with wired network connections? Wired network connections are generally recommended because if either device is on wifi and has a weak(ish) wifi signal strength then Plex Server may be saturating that network connection when there is high network demand. A nearby wifi station using the same wifi channel could also be causing interference.
So the two recordings that showed 29 minutes were both around 29:54, so still lost a few seconds of each episode.
Everything is on a wired Gb network on a single 24 port business class switch. I am a network engineer by trade so I know it’s all set up correctly. The NAS itself is fully capable of close to Gb transfers so I don’t think it’s a NAS problem. I’m going to continue to test for a while.
Still seeing some problems, mostly on 1 hour shows coming up short by 4 or more minutes.
Here are the latest logs:
Plex Media Server Logs_2025-05-22_10-07-38.zip (5.1 MB)
Still seeing some issues with incomplete recordings, mostly on 1 hour shows but occasionally on 30 minute shows as well.
Latest log:
Plex Media Server Logs_2025-05-26_10-10-08.zip (5.5 MB)
Fewer failures, but still seeing some. One half hour show only recorded 3 minutes, hour long shows consistently recording 4-6 minutes short. I really appreciate all the help I’ve gotten to this point, but I’m still disappointed that after posting on this same issue periodically for over a year I still have gotten no help from Plex for a feature that I paid for. Having spent the last 30 years involved in one way or another with tech support organizations it’s disheartening to see a company take money from their customers and provide such poor support in return.
Anyway, here’s the latest log:
Plex Media Server Logs_2025-05-28_10-51-18.zip (3.6 MB)
I’ve fallen into a pattern where almost all 30 minute shows record fine, but every 1 hour show is coming 5-10 minutes short. It’s very frustrating that this feature is still broken for me. I don’t want to resort to reverting to a much older version of the server software, and I am also reluctant to completely kill the server and start from scratch. There must be something I can do to fix this.
Here’s the latest log:
Plex Media Server Logs_2025-05-31_11-15-49.zip (5.0 MB)
Still seeing several shows recording short of complete, both half hour and one hour shows, more frequently failing on one hour shows. Overall it’s gotten better with some of the suggestions I’ve gotten here, but still seeing a lot of failures that I didn’t see with the older version of the server software. I’m going to keep updating this until I get it fixed.
Latest log:
Plex Media Server Logs_2025-06-04_11-07-19.zip (4.8 MB)
Still seeing several shows recording short of complete, both half hour and one hour shows, more frequently failing on one hour shows. Overall it’s gotten better with some of the suggestions I’ve gotten here, but still seeing a lot of failures that I didn’t see with the older version of the server software.
Here’s the latest log:
Plex Media Server Logs_2025-06-06_11-48-32.zip (4.2 MB)
Still seeing several shows recording short of complete, both half hour and one hour shows, more frequently failing on one hour shows. Overall it’s gotten better with some of the suggestions I’ve gotten here, but still seeing a lot of failures that I didn’t see with the older version of the server software. Just updated to the latest server version but nothing in the release notes makes me think it will matter. There must be something in my config that is the problem but I don’t know what it is.
Here’s the latest log:
Plex Media Server Logs_2025-06-12_11-05-33.zip (3.5 MB)
Now it seems I have one TV show, a one hour show, that comes up short every time. It consistently only records 56 or 57 minutes. I’ve tried cancelling it’s recording and recreating it, didn’t make a difference. It records at 3 in the morning with nothing else going on so it’s not a conflict. I don’t understand what’s happening.
Here’s the latest log:
Plex Media Server Logs_2025-06-18_10-38-12.zip (4.2 MB)
Was any of the show cut off?
According to the logs it didn’t start recording till 3:04, if that is what the EPG had then it is correct.
Jun 18, 2025 03:04:00.577 [11924] DEBUG - [Grabber/5fc76c796b022a002d86984e-5fc705f7088e7d002d4c7179-c192d2a18980d9050e1e92462af74249/7b8382e679cc84e96254367cb437c2d3762ee4ce] Starting operation for 'Barnaby Jones - E9 - Renegade's Child' on channel 5fc76c796b022a002d86984e-5fc705ec4c4901002fec3fad.
Jun 18, 2025 03:04:00.577 [11924] DEBUG - [Grabber/5fc76c796b022a002d86984e-5fc705f7088e7d002d4c7179-c192d2a18980d9050e1e92462af74249/7b8382e679cc84e96254367cb437c2d3762ee4ce] Activity: registered new activity 68a0faf7-15e4-4364-8768-667a2a0a8179 - "Recording"
Jun 18, 2025 03:04:00.577 [11924] DEBUG - [Grabber/5fc76c796b022a002d86984e-5fc705f7088e7d002d4c7179-c192d2a18980d9050e1e92462af74249/7b8382e679cc84e96254367cb437c2d3762ee4ce] Grabber: Remote starting a media grab on device device://tv.plex.grabbers.tunerservice/dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6b180070%26rev_04%236%262a91e53a%260%26001000e8%23 (Hauppauge WinTV-quadHD, supported) tuner 0.
After double checking, you are correct. That is the way it’s scheduled. Just a weird scheduling with apparently fewer ads than is typical for an hour long show. I completely missed the obvious here. Sometimes I’m OK with being made to look dumb if it answers the question. Thanks for the help!
This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.
