shows are being aborted

Same problem here. I’ve seen this error several times and no apparent solutions on the forums.

Same issue :confused:

Server ver: 1.5.1.3520
Tuner: HD-US

Here are my logs:
Mar 28, 2017 09:35:50.098 [0400] DEBUG - Activity: Ended activity 5ce66a57-e33a-4e43-88a8-8a14798239b1.
Mar 28, 2017 09:35:50.258 [0400] DEBUG - Activity: registered new activity 0570de17-5893-40d5-9694-0dd2ac503929 - Processing subscriptions
Mar 28, 2017 09:35:50.258 [6208] DEBUG - MediaSub: Processing subscriptions.
Mar 28, 2017 09:35:50.270 [6208] DEBUG - MediaSub: Initialized 1 schedulers.
Mar 28, 2017 09:35:50.270 [6208] DEBUG - MediaSub: Looking for something of type 4 (grandparentGuid: com.gracenote.onconnect://show/9175156 grandparentThumb: http://tmsimg.plex.tv/assets/p10847891_b1t_v5_aa.jpg grandparentTitle: Daniel Tiger’s Neighborhood grandparentYear: 2012 guid: com.gracenote.onconnect://episode/EP015507510012 index: 12 originallyAvailableAt: 2012-09-18 parentIndex: 1 title: Fruit Picking Day; Daniel Is Big Enough to Help Dad type: 4 year: 2012)
Mar 28, 2017 09:35:50.272 [6208] DEBUG - MediaSub: Failed to resolve subscription for ‘grandparentGuid: com.gracenote.onconnect://show/9175156 grandparentThumb: http://tmsimg.plex.tv/assets/p10847891_b1t_v5_aa.jpg grandparentTitle: Daniel Tiger’s Neighborhood grandparentYear: 2012 guid: com.gracenote.onconnect://episode/EP015507510012 index: 12 originallyAvailableAt: 2012-09-18 parentIndex: 1 title: Fruit Picking Day; Daniel Is Big Enough to Help Dad type: 4 year: 2012’ to library item
Mar 28, 2017 09:35:50.272 [6208] DEBUG - MediaSub: Not checking provider Library, type not supported.
Mar 28, 2017 09:35:50.272 [6208] DEBUG - MediaSub: Checking in provider Program Guide
Mar 28, 2017 09:35:50.272 [6208] DEBUG - MediaSub: MATCH for GUID scheme, directly checking.
Mar 28, 2017 09:35:50.272 [6208] DEBUG - Downloading document http://127.0.0.1:32400/tv.plex.providers.epg.onconnect:6/metadata/com.gracenote.onconnect%3A%2F%2Fepisode%2FEP015507510012
Mar 28, 2017 09:35:50.272 [6208] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Mar 28, 2017 09:35:50.272 [6208] DEBUG - Auth: Came in with the master token, authorization succeeded.
Mar 28, 2017 09:35:50.274 [6208] DEBUG - It took 0.0 sec to serialize a list with 1 elements.
Mar 28, 2017 09:35:50.274 [6208] DEBUG - MediaSub: The item at /tv.plex.providers.epg.onconnect:6/metadata/com%2Egracenote%2Eonconnect%3A%2F%2Fepisode%2FEP015507510012 is of type 4
Mar 28, 2017 09:35:50.274 [6208] DEBUG - Downloading document http://127.0.0.1:32400/tv.plex.providers.epg.onconnect:6/metadata/com.gracenote.onconnect%3A%2F%2Fshow%2F9175156
Mar 28, 2017 09:35:50.274 [6208] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Mar 28, 2017 09:35:50.274 [6208] DEBUG - Auth: Came in with the master token, authorization succeeded.
Mar 28, 2017 09:35:50.284 [6208] DEBUG - There were 0 top-level paths for Daniel Tiger’s Neighborhood.
Mar 28, 2017 09:35:50.284 [6208] DEBUG - It took 0.0 sec to serialize a list with 1 elements.
Mar 28, 2017 09:35:50.284 [6208] DEBUG - MediaSub: There are 1 versions of the airing available.
Mar 28, 2017 09:35:50.284 [6208] DEBUG - MediaSub: No match in the library for ‘E12 - Fruit Picking Day; Daniel Is Big Enough to Help Dad’, grabbing.
Mar 28, 2017 09:35:50.284 [6208] DEBUG - MediaRec: Attempting schedule for item: Fruit Picking Day; Daniel Is Big Enough to Help Dad
Mar 28, 2017 09:35:50.284 [6208] DEBUG - MediaRec: Trying airing at 2017-03-28 09:30:00 on channel 2.1.
Mar 28, 2017 09:35:50.284 [6208] DEBUG - MediaRec: Device 0 scheduled recording between 2017-03-28 09:30:00 and 2017-03-28 10:00:00 on channel 2.1
Mar 28, 2017 09:35:50.288 [6208] DEBUG - MediaGrabber: Starting grab immediately for ‘E12 - Fruit Picking Day; Daniel Is Big Enough to Help Dad’ and we’re 350 seconds in already.
Mar 28, 2017 09:35:50.288 [6208] DEBUG - Remote Grabber: Starting a media grab on device device://tv.plex.grabbers.tunerservice/1015A375 tuner 0.
Mar 28, 2017 09:35:50.296 [6208] DEBUG - MediaGrabber: Starting operation for ‘E12 - Fruit Picking Day; Daniel Is Big Enough to Help Dad’
Mar 28, 2017 09:35:50.296 [6208] DEBUG - Activity: registered new activity f10ed89d-d61b-4a3f-ab98-03245ea99290 - Recording
Mar 28, 2017 09:35:50.296 [6208] DEBUG - MediaSub: Added grab.
Mar 28, 2017 09:35:50.296 [6208] DEBUG - Activity: updated activity 0570de17-5893-40d5-9694-0dd2ac503929 - completed 100% - Processing subscriptions
Mar 28, 2017 09:35:50.296 [6208] DEBUG - MediaGrabber: There are 4 grab operations to sweep.
Mar 28, 2017 09:35:50.298 [7060] DEBUG - MediaSub: Failed to resolve subscription for ‘grandparentGuid: com.gracenote.onconnect://show/9175156 grandparentThumb: http://tmsimg.plex.tv/assets/p10847891_b1t_v5_aa.jpg grandparentTitle: Daniel Tiger’s Neighborhood grandparentYear: 2012 guid: com.gracenote.onconnect://episode/EP015507510012 index: 12 originallyAvailableAt: 2012-09-18 parentIndex: 1 title: Fruit Picking Day; Daniel Is Big Enough to Help Dad type: 4 year: 2012’ to library item
Mar 28, 2017 09:35:50.298 [6968] INFO - Notification: Recording E12 - Fruit Picking Day; Daniel Is Big Enough to Help Dad -
Mar 28, 2017 09:35:50.338 [6208] DEBUG - Activity: Ended activity 0570de17-5893-40d5-9694-0dd2ac503929.
Mar 28, 2017 09:35:50.338 [0400] DEBUG - Activity: updated activity f10ed89d-d61b-4a3f-ab98-03245ea99290 - completed 19% - Recording
Mar 28, 2017 09:35:50.340 [7060] VERBOSE - Sufficient disk space: 4GB source file, 32.75TB capacity, 13.82TB available on J: v.grab
Mar 28, 2017 09:35:50.340 [7060] DEBUG - Media Grabber: Saving recording to J: v.grab\1b566444489a6a3f21b7cdc064046e17adfc7a18
Mar 28, 2017 09:35:50.426 [7060] DEBUG - MediaGrabber: Going to record for 1450 seconds, final URL is http://127.0.0.1:32600/devices/dvb%23hdhr%230%23269853557/media/2.1
Mar 28, 2017 09:35:50.458 [7060] DEBUG - MediaGrabber: Waiting for a tuner (2 available).
Mar 28, 2017 09:35:50.458 [7060] DEBUG - MediaGrabber: Allocated a tuner (1 left)
Mar 28, 2017 09:35:50.458 [7060] DEBUG - MediaGrabber: Setting manual timer to stop grab.
Mar 28, 2017 09:35:50.458 [7060] DEBUG - HTTP requesting GET http://127.0.0.1:32600/devices/dvb%23hdhr%230%23269853557/media/2.1
Mar 28, 2017 09:35:50.620 [7060] DEBUG - HTTP 500 response from GET http://127.0.0.1:32600/devices/dvb%23hdhr%230%23269853557/media/2.1
Mar 28, 2017 09:35:50.644 [7060] DEBUG - MediaRecorder: Recording completed with HTTP status 500
Mar 28, 2017 09:35:50.644 [7060] DEBUG - MediaGrabber: Operation for E12 - Fruit Picking Day; Daniel Is Big Enough to Help Dad completed with status 5 (The recording was aborted)
Mar 28, 2017 09:35:50.644 [7060] DEBUG - MediaGrabber: Freed a tuner (now 2 available)
Mar 28, 2017 09:35:53.338 [1924] DEBUG - Activity: Ended activity f10ed89d-d61b-4a3f-ab98-03245ea99290.
Mar 28, 2017 09:35:53.958 [2232] DEBUG - TrayIcon::WindowProc received DisplayIcon timer message
Mar 28, 2017 09:35:53.958 [2232] WARN - Shell_NotifyIcon(NIM_ADD) failed: 0x0
Mar 28, 2017 09:35:58.959 [2232] DEBUG - TrayIcon::WindowProc received DisplayIcon timer message
Mar 28, 2017 09:35:58.959 [2232] WARN - Shell_NotifyIcon(NIM_ADD) failed: 0x0
Mar 28, 2017 09:36:03.956 [2232] DEBUG - TrayIcon::WindowProc received DisplayIcon timer message
Mar 28, 2017 09:36:03.956 [2232] WARN - Shell_NotifyIcon(NIM_ADD) failed: 0x0

Same issue here.

Saw it last week - a whole day of recordings aborted before I noticed. Stopped and restarted Plex, and rebooted the PC with no change. Re-installed Plex, and all was well for a couple of days.

Problem returned today. Version 1.4.4.3495.

I’ll add that I’m still having the same problem, but with recent update to support older HDHomerun devices, I had slightly better luck with my old dual. Some shows would at least record for a few minutes and remain but I noticed the quality deteriorated (blocky) at the end before it stopped. Previously the Extend was reporting good signal quality, and if I stream it directly seemed fine. So it makes me wonder if the Plex DVR is particularly picky about signal quality affecting the stream.

Any help from the Plex team on this one? This happened again with The Walking Dead yesterday and that is not OK! :# :smiley: :wink:

Any Better in 1.5.2???

same on 1.5.3. this sucks

How does it appear when it occurs? Last night I had 3 shows scheduled. I noticed the first being recorded with the two subsequent shows on the schedule. This morning the 2 remaining shows don’t appear on the schedule and appear to not have been recorded. The two missing recordings were from the same channel.

I have two HDHR Extends (firmware up-to-date - and set to Highest Quality for transcoding) with Plex Server running on a Synology DS916+. The server was on version 1.4.3 yesterday when I had problems, but I upgraded it this morning to 1.5.3. I tried combing through the logs, but I didn’t have anything far enough back.

I don’t know if Plex tried to record it, but failed for some reason or if Plex abruptly decided to remove the shows from the schedule. This hasn’t happened very often. Perhaps once before a few months back.

Yes, the shows disappear from recording history like you describe. If you happen to be looking at it shortly after it aborts near where the recording process indicator was there’s a message it was aborted, otherwise they’re gone.

I’ve had this happen a few times on a single channel that sometimes comes in poorly. I don’t know for sure if this is the reason though. There are plenty of times when I get a full recording with too much data loss to even watch the episode (also plenty of episodes that come in fine, depending on cloud cover I think). So it’s not aborting the recording simply because of a few bad bits.

If it’s related to a bad signal then I don’t know what the key trigger would be. It’s not simply a few missing bits.

I have a CONNECT (based in the UK) and I can confirm that if two new recordings overlap (even if consecutive shows on the same channel with a minute padding for each recording causing the small overlap) then the PVR aborts the recording.

EDIT: Please disregard this post - I believe I have identified an issue relating to the freeview channel which the aborted recordings related to. I don’t believe this is a Plex issue, per se.

I just experienced this tonight. It started to record American Crime and aborted after 3 minutes. I have a connect (HDHR4-2US), extend (HDTC-2US), and 2 duals (HDHR3-US) running the latest firmware for all. PMS running on Ubuntu 16.04.2 LTS with 8 GB ram, 3 TB disk, quad core AMD A8-6600K.

This may not help most of you, but I was having the problem of recordings being aborted and found that I had to actually have the Plex server app running in the foreground, not just on and in the tray. I also needed the schedule updated for it to work. It will not just go to the station and start recording based on the recording schedule. When I brought up the Plex server from the tray and after it finished updating the the schedule, it allowed me to start a recording of a program in progress, not before. I don’t know if now it would allow recording when the Plex Server is just in the tray.

Update, Did not work on the second scheduled show. Luckily I also have WinTV 8 and I will be cancelling the Plex DVR

@remmeler I definitely don’t have to have Plex open. It sits in my task tray all day recording shows. Don’t see where you have posted any other information about your setup on this thread. You say luckily you have WinTV 8, are you using the same HDHR device for Plex and WinTV 8? If so are you getting tuner conflicts causing the recordings to fail? More information would be good to aid in diagnosing what problem you are experiencing.

@“Dean Junk” said:
I just experienced this tonight. It started to record American Crime and aborted after 3 minutes. I have a connect (HDHR4-2US), extend (HDTC-2US), and 2 duals (HDHR3-US) running the latest firmware for all. PMS running on Ubuntu 16.04.2 LTS with 8 GB ram, 3 TB disk, quad core AMD A8-6600K.

It did the same thing on April 30th for American Crime. Before it started to record, I cancelled the Star Trek schedules just in case there was some kind of a conflict for open tuners (even though I have plenty available). I don’t have anything else accessing the tuners but PMS.

I am having the same problem. It aborts the recording but gives me no reason. I have HDHomerun DVR recording a few things so I thought there might have been a conflict but I don’t see one and since it doesn’t tell me why it aborts, I’m having trouble fixing the problem.

same problem with me. after a few seconds. it gets aborted

@“Dean Junk” said:
I just experienced this tonight. It started to record American Crime and aborted after 3 minutes. I have a connect (HDHR4-2US), extend (HDTC-2US), and 2 duals (HDHR3-US) running the latest firmware for all. PMS running on Ubuntu 16.04.2 LTS with 8 GB ram, 3 TB disk, quad core AMD A8-6600K.

I’m no expert, but I’ve tried to troubleshoot my own recordings a few times.

Some lines in your log that might be interesting:

Apr 23, 2017 21:00:00.127 [0x7f7cc9bfb700] DEBUG - MediaSub: Resolved subscription to library item 'Star Trek: Deep Space Nine' (2497466)
Apr 23, 2017 21:00:00.129 [0x7f7cc9bfb700] DEBUG - There were 1 top-level paths for Star Trek: Deep Space Nine.
Apr 23, 2017 21:00:00.129 [0x7f7cc9bfb700] DEBUG - Media Grabber: Saving recording to /mnt/plexdvr/TV Shows/.grab/817b47bbad9ac734fdcf74fa70f518041268a6c5
Apr 23, 2017 21:00:00.129 [0x7f7cc9bfb700] DEBUG - MediaGrabber: Going to record for 3600 seconds, final URL is http://192.168.0.151:5004/auto/v8.3?duration=3600
Apr 23, 2017 21:00:00.129 [0x7f7cc9bfb700] DEBUG - MediaGrabber: Waiting for a tuner (0 available).
...
Apr 23, 2017 21:00:00.725 [0x7f7cd8bff700] DEBUG - MediaSub: Resolved subscription to library item 'American Crime' (2502624)
Apr 23, 2017 21:00:00.748 [0x7f7cd8bff700] DEBUG - There were 1 top-level paths for American Crime.
Apr 23, 2017 21:00:00.749 [0x7f7cd8bff700] DEBUG - Media Grabber: Saving recording to /mnt/plexdvr/TV Shows/.grab/19403b0c5706c8a7472766369b03f1a316de3d38Apr 23, 2017 21:00:00.749 [0x7f7cd8bff700] DEBUG - MediaGrabber: Going to record for 3600 seconds, final URL is http://192.168.0.156:5004/auto/v5.1?duration=3600&transcode=none
Apr 23, 2017 21:00:00.749 [0x7f7cd8bff700] DEBUG - MediaGrabber: Waiting for a tuner (2 available).
Apr 23, 2017 21:00:00.749 [0x7f7cd8bff700] DEBUG - MediaGrabber: Allocated a tuner (1 left)
Apr 23, 2017 21:00:00.749 [0x7f7cd8bff700] DEBUG - HTTP requesting GET http://192.168.0.156:5004/auto/v5.1?duration=3600&transcode=none

Above we see Plex beginning to record an episode of DS9 and an episode of American Crime. In each case Plex wants to record for 3600 seconds and asks the HDHomeRun to provide a stream for this duration.

Apr 23, 2017 21:04:47.090 [0x7f7cd8bff700] DEBUG - HTTP 200 response from GET http://192.168.0.156:5004/auto/v5.1?duration=3600&transcode=none
Apr 23, 2017 21:04:47.090 [0x7f7cd8bff700] DEBUG - MediaRecorder: Recording completed with HTTP status 200
Apr 23, 2017 21:04:47.090 [0x7f7cd8bff700] DEBUG - MediaGrabber: Operation for E7 - Season Three: Episode Seven completed with status 3 (Success)
Apr 23, 2017 21:04:47.090 [0x7f7cd8bff700] DEBUG - MediaGrabber: Freed a tuner (now 2 available)
Apr 23, 2017 21:04:47.090 [0x7f7cd8bff700] DEBUG - Job running: '/home/junkdp/scripts/runpostprocess.sh' '/mnt/plexdvr/TV Shows/.grab/19403b0c5706c8a7472766369b03f1a316de3d38/American Crime (2015) - S03E07 - Season Three Episode Seven.ts'

After 4 minutes 47 seconds, Plex already receives the final HTTP 200 response (“OK”) from the HDHomeRun for the American Crime episode. As far as I know this tells Plex that the recording has ended, and Plex trusts the HDHomerun when it says “I gave you what you asked for”.

Apr 23, 2017 22:00:00.823 [0x7f7cc9bfb700] DEBUG - HTTP 200 response from GET http://192.168.0.151:5004/auto/v8.3?duration=3600
Apr 23, 2017 22:00:00.824 [0x7f7cc9bfb700] DEBUG - MediaRecorder: Recording completed with HTTP status 200
Apr 23, 2017 22:00:00.824 [0x7f7cc9bfb700] DEBUG - MediaGrabber: Operation for E13 - Life Support completed with status 3 (Success)
Apr 23, 2017 22:00:00.824 [0x7f7cc9bfb700] DEBUG - MediaGrabber: Freed a tuner (now 1 available)
Apr 23, 2017 22:00:00.824 [0x7f7ccabfd700] DEBUG - MediaGrabber: Allocated a tuner (0 left)
Apr 23, 2017 22:00:00.824 [0x7f7cc9bfb700] DEBUG - Job running: '/home/junkdp/scripts/runpostprocess.sh' '/mnt/plexdvr/TV Shows/.grab/817b47bbad9ac734fdcf74fa70f518041268a6c5/Star Trek Deep Space Nine (1993) - S03E13 - Life Support.ts'

After one hour, Plex gets the 200 response for the DS9 episode.

So it seems to me that there is some kind of problem with the HDHomeRun device, especially because it tells Plex everything is OK when it doesn’t deliver the 3600 seconds that were requested. Then Plex could of course also detect that the stream has ended prematurely and mark the recording as incomplete (not least because there could be other opportunities to record the episode as long as you don’t wait for the user to detect the bad recording and delete it manually).

Is there any relevant information in the HDHomeRun log (in the tuner’s web interface) at the time the recording ended? For example, I got this message when one of my recordings completely failed:

20170509-18:59:00 Tuner: tuner3 tuning 6 TV6 (a8qam256-6875:290MHz-1135)
20170509-18:59:00 Tuner: tuner2 streaming http to 192.168.42.42:43714
20170509-18:59:05 HTTP: rejecting request from 192.168.42.42 - no video data

(Other aborted recordings could be for the same reason or for completely different reasons – completely impossible to tell without the logs.)

Did the DS9 episode even record? Plex shows 0 available tuners.
Apr 23, 2017 21:00:00.129 [0x7f7cc9bfb700] DEBUG - MediaGrabber: Going to record for 3600 seconds, final URL is http://192.168.0.151:5004/auto/v8.3?duration=3600
Apr 23, 2017 21:00:00.129 [0x7f7cc9bfb700] DEBUG - MediaGrabber: Waiting for a tuner (0 available).

So the HDHR at 192.168.0.151 had no tuners available. However the tuner at 192.168.0.156 had 2 tuners available for the other recording.
Apr 23, 2017 21:00:00.749 [0x7f7cd8bff700] DEBUG - Media Grabber: Saving recording to /mnt/plexdvr/TV Shows/.grab/19403b0c5706c8a7472766369b03f1a316de3d38Apr 23, 2017 21:00:00.749 [0x7f7cd8bff700] DEBUG - MediaGrabber: Going to record for 3600 seconds, final URL is http://192.168.0.156:5004/auto/v5.1?duration=3600&transcode=none
Apr 23, 2017 21:00:00.749 [0x7f7cd8bff700] DEBUG - MediaGrabber: Waiting for a tuner (2 available).

Not sure why Plex wouldn’t use the HDHR with 2 available tuners. If there a difference in channels selected for your different HDHR? Which HDHR is the 192.168.0.151 device?