I noticed an issue while my DVR was attempting to schedule two shows at the same time. It appears that the they both attempted to take the same tuner and the last one in won:
Log from HDHomeRun Prime:
20181113-19:59:59 Tuner: tuner0 tuning 505 WCVB-DTV (auto:507MHz-3103)
20181113-20:00:00 Tuner: tuner0 streaming http to 192.168.1.249:36840
20181113-20:00:00 CableCARD: tuner0 505 WCVB-DTV (auto:507MHz-3103) access = subscribed
20181113-21:00:02 Tuner: tuner0 http stream ended (remote closed)
20181114-01:59:59 Tuner: tuner0 tuning 710 MTV HD (East) (auto:249MHz-640)
20181114-02:00:00 Tuner: tuner0 streaming http to 192.168.1.249:58520
20181114-02:00:00 CableCARD: tuner0 710 MTV HD (East) (auto:249MHz-640) access = subscribed
However the Plex log shows a different story:
ov 13, 2018 21:00:00.796 [0x7f8211bff700] DEBUG - DVR:Grabber: Starting operation for 'FBI - E7 - Cops and Robbers'.
Nov 13, 2018 21:00:00.796 [0x7f8211bff700] DEBUG - Activity: registered new activity b936555a-4fb3-4516-88e1-ba474aa7e2d8 - Recording
Nov 13, 2018 21:00:00.797 [0x7f8211bff700] DEBUG - DVR:Grabber: HDHomerun starting a media grab on device device://tv.plex.grabbers.hdhomerun/1321E38C (Silicondust HDHomeRun PRIME, supported) **tuner 2.**
Nov 13, 2018 21:00:00.798 [0x7f8211bff700] DEBUG - DVR:Subscription: Resolved subscription to library item 'FBI' (6614)
Nov 13, 2018 21:00:00.801 [0x7f8211bff700] DEBUG - There were 1 top-level paths for FBI.
Nov 13, 2018 21:00:00.802 [0x7f8211bff700] DEBUG - DVR:Grabber: Saving recording to "/mnt/nas/DVR/TV/.grab/271c81aa824173d57df343df7a758978b7b8c63c"
Nov 13, 2018 21:00:00.803 [0x7f8211bff700] DEBUG - HTTP requesting GET http://192.168.1.165:80/lineup.json
Nov 13, 2018 21:00:00.821 [0x7f8211bff700] DEBUG - HTTP 200 response from GET http://192.168.1.165:80/lineup.json
Nov 13, 2018 21:00:00.825 [0x7f8211bff700] DEBUG - DVR:Grabber: Going to record for 3600 seconds, final URL is http://192.168.1.165:5004/auto/v504
Nov 13, 2018 21:00:00.826 [0x7f8211bff700] DEBUG - MediaRecorderVirtual: setting stop time to 2018-11-13 22:00:00
Nov 13, 2018 21:00:00.826 [0x7f8211bff700] ERROR - DVR:Recorder: Error 0 (Success) starting the record, shutting things down.
Nov 13, 2018 21:00:00.826 [0x7f8211bff700] DEBUG - MediaRecorderVirtual: setting stop time to 2018-11-13 21:00:00
Nov 13, 2018 21:00:00.826 [0x7f8211bff700] DEBUG - DVR:Recorder: Stopping transcode session.
Nov 13, 2018 21:00:00.826 [0x7f8211bff700] DEBUG - Job was already killed, not killing again.
Nov 13, 2018 21:00:00.826 [0x7f8211bff700] DEBUG - Job was already killed, not killing again.
Nov 13, 2018 21:00:00.826 [0x7f8211bff700] DEBUG - DVR:Grabber: Freed a tuner (now 2 available)
Nov 13, 2018 21:00:00.826 [0x7f8211bff700] ERROR - DVR:Recorder: Error 0 (Success) starting the record, shutting things down.
ov 13, 2018 21:00:00.826 [0x7f8211bff700] DEBUG - MediaRecorderVirtual: setting stop time to 2018-11-13 21:00:00
Nov 13, 2018 21:00:00.826 [0x7f8211bff700] DEBUG - DVR:Grabber: Operation for FBI - E7 - Cops and Robbers completed with status error (Success)
Nov 13, 2018 21:00:00.826 [0x7f8211bff700] DEBUG - Activity: Ended activity b936555a-4fb3-4516-88e1-ba474aa7e2d8.
Nov 13, 2018 21:00:00.826 [0x7f8211bff700] ERROR - DVR:Grabber: Starting media grab failed.
Plex clearly attempts to use Tuner 2, but the HD Box attempts (and successfully, at first, tunes with Tuner 0) - I am not sure if the Plex logged one thing and issued a different command. But seems like at the very least Plex should attempt to recover from these types of situations. I would love to see a retry mechanism for things like this.
I will attempt to upload logs as well for reference.Plex Media Server.log (3.4 MB)
