"Unable to tune channel (No parts)" and failed DVR recordings

General Discussions Plex Features
1

Hardware:

  • Intel Intel Xeon E3-1245 v5
  • 32 GB RAM
  • HDHomeRun Connect

OS: unRaid v6.4. Plex is running in a Docker.

Plex version: 1.11.2.4772

Issue: Plex Live TV and DVR work fine after a fresh reboot, but will gradually become unable to tune certain channels (seemingly a channel I had previously watched or made a recording on) or make DVR recordings until a new reboot. When attempting to tune a channel, the error messages will differ based on the client’s device. When using Win7 and Chrome, I receive the message: “Unable to tune channel (No parts)”. I am confident that the issue is the Plex server because I can tune channels without issue using the HDHR software.

For example, Plex missed a recording today at noon of the Olympics. Interestingly, when the failed recording is attempted, Plex believes I have 10 tuners available (I do not – I have 2 tuners available). After noticing the failure, I rebooted, and Plex immediately began recording the program.

Partial log of the failed recording (from Plex Media Server.4.log):

DEBUG - DVR:Grabber: Starting operation for '2018 Winter Olympics - Speed Skating, Biathlon, Cross-Country Skiing'.
Feb 11, 2018 20:00:00.177 [0x14aeda3ff700] DEBUG - Activity: registered new activity b892fcc0-fb40-46e6-9a39-05f9232ff1da - Recording
Feb 11, 2018 20:00:00.178 [0x14aeda3ff700] DEBUG - DVR:Grabber: HDHomerun starting a media grab on device device://tv.plex.grabbers.hdhomerun/1049953A (Silicondust HDHomeRun CONNECT, supported) tuner 0.
Feb 11, 2018 20:00:00.178 [0x14aef4fff700] DEBUG - Thread: Running async task started by thread 0x14aeda3ff700.
Feb 11, 2018 20:00:00.180 [0x14aeda3ff700] DEBUG - DVR:Subscription: Resolved subscription to library item '2018 Winter Olympics' (55342)
Feb 11, 2018 20:00:00.182 [0x14aeda3ff700] DEBUG - There were 1 top-level paths for 2018 Winter Olympics.
Feb 11, 2018 20:00:00.183 [0x14aeda3ff700] DEBUG - DVR:Grabber: Saving recording to /TV/.grab/eb43726df2459f5bef4f52a55e5f1d0e520ce744
Feb 11, 2018 20:00:00.201 [0x14aeda3ff700] DEBUG - HTTP requesting GET http://192.168.1.227:80/lineup.json
Feb 11, 2018 20:00:00.227 [0x14aeda3ff700] DEBUG - HTTP 200 response from GET http://192.168.1.227:80/lineup.json
Feb 11, 2018 20:00:00.229 [0x14aeda3ff700] DEBUG - DVR:Grabber: Going to record for 10800 seconds, final URL is http://192.168.1.227:5004/auto/v4.1
Feb 11, 2018 20:00:00.230 [0x14aeda3ff700] DEBUG - DVR:Grabber: Setting timer to stop grab of '2018 Winter Olympics - Speed Skating, Biathlon, Cross-Country Skiing' in 10800 seconds.
Feb 11, 2018 20:00:00.230 [0x14aeda3ff700] DEBUG - DVR:Grabber: Waiting for a tuner (10 available).
Feb 11, 2018 20:00:00.230 [0x14aeda3ff700] DEBUG - DVR:Grabber: Allocated a tuner (9 left)
Feb 11, 2018 20:00:00.230 [0x14aeda3ff700] ERROR - DVR:Recorder: Error 0 (Success) starting the record, shutting things down.
Feb 11, 2018 20:00:00.230 [0x14aeda3ff700] DEBUG - MediaRecorderVirtual: setting stop time to 1518379200
Feb 11, 2018 20:00:00.230 [0x14aeda3ff700] DEBUG - DVR:Recorder: Stopping transcode session.
Feb 11, 2018 20:00:00.230 [0x14aeda3ff700] DEBUG - Job was already killed, not killing again.
Feb 11, 2018 20:00:00.230 [0x14aeda3ff700] DEBUG - Job was already killed, not killing again.
Feb 11, 2018 20:00:00.230 [0x14aeda3ff700] DEBUG - DVR:Grabber: Freed a tuner (now 10 available)
Feb 11, 2018 20:00:00.230 [0x14aeda3ff700] ERROR - DVR:Recorder: Error 0 (Success) starting the record, shutting things down.
Feb 11, 2018 20:00:00.230 [0x14aeda3ff700] DEBUG - MediaRecorderVirtual: setting stop time to 1518379200
Feb 11, 2018 20:00:00.230 [0x14aeda3ff700] DEBUG - DVR:Grabber: Operation for 2018 Winter Olympics - Speed Skating, Biathlon, Cross-Country Skiing completed with status error (Success)
Feb 11, 2018 20:00:00.231 [0x14aeda3ff700] DEBUG - Activity: Ended activity b892fcc0-fb40-46e6-9a39-05f9232ff1da.
Feb 11, 2018 20:00:00.232 [0x14aeda3ff700] ERROR - DVR:Grabber: Starting media grab failed.
Feb 11, 2018 20:00:00.303 [0x14aef07ff700] DEBUG - Thread: Running async task started by thread 0x14aef8dfe700.
Feb 11, 2018 20:00:00.304 [0x14aef07ff700] DEBUG - Request: [104.254.4.147:49817 (WAN)] GET /video/:/transcode/universal/dash/sm1f9kkxu1lqidp16w5ifzpm/1/458.m4s (15 live) TLS GZIP Signed-in
Feb 11, 2018 20:00:00.304 [0x14aef07ff700] DEBUG - Asked for segment 458 from session.
Feb 11, 2018 20:00:00.304 [0x14aef07ff700] DEBUG - Returning segment 458 from session: /config/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-sm1f9kkxu1lqidp16w5ifzpm-8ebdcb9b-c244-40a3-9930-0680a20ca7a3/chunk-stream1-00459.m4s
Feb 11, 2018 20:00:00.304 [0x14aef65fe700] DEBUG - Thread: Running async task started by thread 0x14aef07ff700.
Feb 11, 2018 20:00:00.304 [0x14aef65fe700] DEBUG - Streaming Resource: Estimated bandwidth for o028sccrxqdpipg17phum48r to be 5734kbps over 12.02s
Feb 11, 2018 20:00:00.304 [0x14aef07ff700] DEBUG - Content-Length of /config/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-sm1f9kkxu1lqidp16w5ifzpm-8ebdcb9b-c244-40a3-9930-0680a20ca7a3/init-stream1.m4s,/config/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-sm1f9kkxu1lqidp16w5ifzpm-8ebdcb9b-c244-40a3-9930-0680a20ca7a3/chunk-stream1-00459.m4s is 42654.
Feb 11, 2018 20:00:00.305 [0x14aef8dfe700] DEBUG - Completed: [104.254.4.147:49817] 200 GET /video/:/transcode/universal/dash/sm1f9kkxu1lqidp16w5ifzpm/1/458.m4s (15 live) TLS GZIP 1ms 42654 bytes (pipelined: 2)
Feb 11, 2018 20:00:00.306 [0x14aee83fd700] DEBUG - Thread: Running async task started by thread 0x14aef8dfe700.
Feb 11, 2018 20:00:00.325 [0x14aee79f8700] DEBUG - Thread: Running async task started by thread 0x14aef8dfe700.
Feb 11, 2018 20:00:00.326 [0x14aee79f8700] DEBUG - Request: [127.0.0.1:39736 (Loopback)] PUT /video/:/transcode/session/sm1f9kkxu1lqidp16w5ifzpm/8ebdcb9b-c244-40a3-9930-0680a20ca7a3/progress?progress=55.4&size=-22&remaining=8443&vdec_packets=42729&vdec_sw_ok=42728&vdec_hw_status=0 (15 live) Signed-in Token (imagoodusername)
Feb 11, 2018 20:00:00.326 [0x14aee79f8700] DEBUG - Session sm1f9kkxu1lqidp16w5ifzpm (4) is unthrottling
Feb 11, 2018 20:00:00.326 [0x14aef8dfe700] DEBUG - Completed: [127.0.0.1:39736] 206 PUT /video/:/transcode/session/sm1f9kkxu1lqidp16w5ifzpm/8ebdcb9b-c244-40a3-9930-0680a20ca7a3/progress?progress=55.4&size=-22&remaining=8443&vdec_packets=42729&vdec_sw_ok=42728&vdec_hw_status=0 (15 live) 0ms 326 bytes
Feb 11, 2018 20:00:00.326 [0x14aef5bff700] DEBUG - Thread: Running async task started by thread 0x14aef8fff700.
Feb 11, 2018 20:00:00.326 [0x14aef5bff700] DEBUG - [Transcoder] Throttle - Getting back to work.
Feb 11, 2018 20:00:00.531 [0x14aed45fc700] DEBUG - Thread: Running async task started by thread 0x14aef8fff700.
Feb 11, 2018 20:00:00.531 [0x14aed45fc700] DEBUG - Request: [104.254.4.147:49817 (WAN)] GET /video/:/transcode/universal/dash/sm1f9kkxu1lqidp16w5ifzpm/0/458.m4s (14 live) TLS GZIP Signed-in
Feb 11, 2018 20:00:00.531 [0x14aed45fc700] DEBUG - Asked for segment 458 from session.
Feb 11, 2018 20:00:00.531 [0x14aed45fc700] DEBUG - Returning segment 458 from session: /config/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-sm1f9kkxu1lqidp16w5ifzpm-8ebdcb9b-c244-40a3-9930-0680a20ca7a3/chunk-stream0-00459.m4s
Feb 11, 2018 20:00:00.531 [0x14aef8bfd700] DEBUG - Thread: Running async task started by thread 0x14aed45fc700.
Feb 11, 2018 20:00:00.531 [0x14aef8bfd700] DEBUG - Streaming Resource: Estimated bandwidth for o028sccrxqdpipg17phum48r to be 4532kbps over 10.23s
Feb 11, 2018 20:00:00.531 [0x14aed45fc700] DEBUG - Content-Length of /config/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-sm1f9kkxu1lqidp16w5ifzpm-8ebdcb9b-c244-40a3-9930-0680a20ca7a3/init-stream0.m4s,/config/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-sm1f9kkxu1lqidp16w5ifzpm-8ebdcb9b-c244-40a3-9930-0680a20ca7a3/chunk-stream0-00459.m4s is 1442642.
Feb 11, 2018 20:00:00.730 [0x14aef67ff700] DEBUG - Thread: Running async task started by thread 0x14aef8fff700.
Feb 11, 2018 20:00:00.730 [0x14aef67ff700] DEBUG - Request: [127.0.0.1:39740 (Loopback)] PUT /video/:/transcode/session/sm1f9kkxu1lqidp16w5ifzpm/8ebdcb9b-c244-40a3-9930-0680a20ca7a3/progress?progress=55.4&size=-22&remaining=3795&vdec_packets=42791&vdec_sw_ok=42790&speed=4.1&vdec_hw_status=0 (14 live) Signed-in Token (imagoodusername)
Feb 11, 2018 20:00:00.730 [0x14aef8fff700] DEBUG - Completed: [127.0.0.1:39740] 206 PUT /video/:/transcode/session/sm1f9kkxu1lqidp16w5ifzpm/8ebdcb9b-c244-40a3-9930-0680a20ca7a3/progress?progress=55.4&size=-22&remaining=3795&vdec_packets=42791&vdec_sw_ok=42790&speed=4.1&vdec_hw_status=0 (14 live) 0ms 326 bytes
Feb 11, 2018 20:00:00.827 [0x14aedd5fa700] DEBUG - Thread: Running async task started by thread 0x14aef8fff700.
Feb 11, 2018 20:00:00.827 [0x14aedd5fa700] DEBUG - Request: [127.0.0.1:39744 (Loopback)] POST /video/:/transcode/session/sm1f9kkxu1lqidp16w5ifzpm/8ebdcb9b-c244-40a3-9930-0680a20ca7a3/manifest (14 live) Signed-in
Feb 11, 2018 20:00:00.830 [0x14aedd5fa700] DEBUG - Transcoder segment range: 0 - 479
Feb 11, 2018 20:00:00.831 [0x14aef8dfe700] DEBUG - Completed: [127.0.0.1:39744] 200 POST /video/:/transcode/session/sm1f9kkxu1lqidp16w5ifzpm/8ebdcb9b-c244-40a3-9930-0680a20ca7a3/manifest (14 live) 4ms 166 bytes
Feb 11, 2018 20:00:00.954 [0x14aef35fe700] DEBUG - Thread: Running async task started by thread 0x14aefa667700.
Feb 11, 2018 20:00:01.032 [0x14aef8fff700] DEBUG - Completed: [104.254.4.147:49817] 200 GET /video/:/transcode/universal/dash/sm1f9kkxu1lqidp16w5ifzpm/0/458.m4s (14 live) TLS GZIP 500ms 1442642 bytes (pipelined: 3)
Feb 11, 2018 20:00:01.032 [0x14aed8bff700] DEBUG - Thread: Running async task started by thread 0x14aef8fff700.
Feb 11, 2018 20:00:01.229 [0x14aeda1fe700] DEBUG - Thread: Running async task started by thread 0x14aef8fff700.
Feb 11, 2018 20:00:01.229 [0x14aeda1fe700] DEBUG - Request: [127.0.0.1:39746 (Loopback)] PUT /video/:/transcode/session/sm1f9kkxu1lqidp16w5ifzpm/8ebdcb9b-c244-40a3-9930-0680a20ca7a3/progress?progress=55.5&size=-22&remaining=254&vdec_packets=42867&vdec_sw_ok=42866&speed=5.1&vdec_hw_status=0 (15 live) Signed-in Token (imagoodusername)
Feb 11, 2018 20:00:01.229 [0x14aeda1fe700] DEBUG - Session sm1f9kkxu1lqidp16w5ifzpm (4) is throttling
Feb 11, 2018 20:00:01.229 [0x14aef8fff700] DEBUG - Completed: [127.0.0.1:39746] 206 PUT /video/:/transcode/session/sm1f9kkxu1lqidp16w5ifzpm/8ebdcb9b-c244-40a3-9930-0680a20ca7a3/progress?progress=55.5&size=-22&remaining=254&vdec_packets=42867&vdec_sw_ok=42866&speed=5.1&vdec_hw_status=0 (15 live) 0ms 342 bytes
Feb 11, 2018 20:00:01.230 [0x14aef07ff700] DEBUG - Thread: Running async task started by thread 0x14aef8fff700.
Feb 11, 2018 20:00:01.230 [0x14aef07ff700] DEBUG - [Transcoder] Throttle - Going into sloth mode.
Feb 11, 2018 20:00:01.494 [0x14aed89fe700] DEBUG - Thread: Running async task started by thread 0x14aefa667700.
Feb 11, 2018 20:00:01.510 [0x14aed89fe700] DEBUG - EPG[onconnect]: Next thing to start/end is at 2018-02-11 20:30:00 (in 1800 seconds)
Feb 11, 2018 20:00:01.596 [0x14aef6fff700] DEBUG - Thread: Running async task started by thread 0x14aefa667700.
Feb 11, 2018 20:00:01.596 [0x14aef6fff700] DEBUG - Streaming Resource: Estimated bandwidth for o028sccrxqdpipg17phum48r to be 5441kbps over 10.56s
Feb 11, 2018 20:00:01.863 [0x14aee79f8700] DEBUG - Thread: Running async task started by thread 0x14aef8dfe700.
Feb 11, 2018 20:00:01.863 [0x14aee79f8700] DEBUG - Request: [127.0.0.1:39752 (Loopback)] PUT /video/:/transcode/session/sm1f9kkxu1lqidp16w5ifzpm/8ebdcb9b-c244-40a3-9930-0680a20ca7a3/progress?progress=55.5&size=-22&remaining=2995&vdec_packets=42870&vdec_sw_ok=42869&vdec_hw_status=0 (14 live) Signed-in Token (imagoodusername)
Feb 11, 2018 20:00:01.864 [0x14aef8dfe700] DEBUG - Completed: [127.0.0.1:39752] 206 PUT /video/:/transcode/session/sm1f9kkxu1lqidp16w5ifzpm/8ebdcb9b-c244-40a3-9930-0680a20ca7a3/progress?progress=55.5&size=-22&remaining=2995&vdec_packets=42870&vdec_sw_ok=42869&vdec_hw_status=0 (14 live) 0ms 342 bytes
Feb 11, 2018 20:00:02.200 [0x14aef8dfe700] DEBUG - Auth: authenticated user 1 as imagoodusername
Feb 11, 2018 20:00:02.201 [0x14aef5bff700] DEBUG - Thread: Running async task started by thread 0x14aef8dfe700.
Feb 11, 2018 20:00:02.201 [0x14aef5bff700] DEBUG - Request: [104.254.4.147:49822 (WAN)] GET /:/timeline?ratingKey=55365&key=%2Flibrary%2Fmetadata%2F55365&playQueueItemID=63707&state=playing&hasMDE=1&time=1305000&duration=2598000 (14 live) TLS GZIP Signed-in Token (imagoodusername)
Feb 11, 2018 20:00:02.201 [0x14aef5bff700] DEBUG - Client [o028sccrxqdpipg17phum48r] reporting timeline state playing, progress of 1305000/2598000ms for guid=, ratingKey=55365 url=, key=/library/metadata/55365, containerKey=, metadataId=55365
Feb 11, 2018 20:00:02.206 [0x14aef5bff700] DEBUG - Play progress on 55365 '2018-02-11' - got played 1305000 ms by account 1!
Feb 11, 2018 20:00:02.215 [0x14aef5bff700] DEBUG - [Now] User is imagoodusername (ID: 1)
Feb 11, 2018 20:00:02.215 [0x14aef5bff700] DEBUG - [Now] Device is Chrome (Plex Web (Chrome)).
Feb 11, 2018 20:00:02.215 [0x14aef5bff700] DEBUG - [Now] Profile is Web
Feb 11, 2018 20:00:02.215 [0x14aef5bff700] DEBUG - [Now] Updated play state for /library/metadata/55365.
Feb 11, 2018 20:00:02.216 [0x14aef5bff700] DEBUG - Statistics: (j5amhcvigiulpwbnmpyjgmw6) Reporting active playback in state 0 of type 4 (scrobble: 0) for account 1
Feb 11, 2018 20:00:02.221 [0x14aed9ffd700] DEBUG - Thread: Running async task started by thread 0x14aef5bff700.
Feb 11, 2018 20:00:02.221 [0x14aed9ffd700] DEBUG - Streaming Resource: Estimated bandwidth for o028sccrxqdpipg17phum48r to be 5441kbps over 11.19s
Feb 11, 2018 20:00:02.223 [0x14aef4fff700]
2

I just ran into the same problem on the Xbox One when trying to record the Olympics on NBC. I’m still on PMS 1.10, but I got the “Unable to tune channel (No parts)” message too. I can’t play the channel on any Plex device, but the channel tunes fine in the HDHomeRun app on Windows 10. Agree that it’s a server issue.

3

So providing an update to my own post. I read in a post in this thread that using Live TV with Chrome seems to be correlated with the issue. The hypothesis is basically:

  1. Chrome/Plex Web tunes to a live TV channel.
  2. User quits Chrome/Plex Web
  3. Chrome/Plex Web fails to unlock the channel (or something similar) or failed initially to notify HDHR of the lock
  4. Plex Server then starts spitting out errors when attempting to re-tune the channel

For the past week, I didn’t use Live TV through Plex Web at all, and my 30-40 recordings all seemed to work fine (with a couple slight hiccups that appear totally unrelated to the “unable to tune channel (no parts)” bug). I also didn’t receive any tuning errors when trying to watch Live TV through Android apps.

I’m going to continue my trial-and-error on this, and I’ll post any updates.

4

Chrome could be a factor, but I don’t have Chrome installed on my PC and I ran into the issue on my Xbox One. Mine locked when I was recording and placeshifting the live recording so maybe placeshifting contributes to the error too.

5

Further updating my post…

I updated to 1.11.3.4803 last weekend. The upgrade seems to have made the problem worse. PMS needs to be rebooted about once a day in order to properly record or tune from one station (and interestingly, the problem seems to just be with one station).

Chrome may still be a culprit with the other issue, but on 1.11.3.4803 there seems to be a bigger problem.

6

I have this using Nexus players & Sony Android TV. Don’t really use Chrome. I’m running on Ubuntu. This seems to be a broad issue and no real feedback from Plex recognizing this is a major issue. I have all TV’s on the house on Plex with 2 HDHomeRun Primes, and about daily now I need to restart plexmediaserver. With a wife and 2 kids that won’t be able to do this, I’m getting to the point I have to move back to regular set top boxes as my family is getting frustrated. Folks at Plex: This can be the future of TV - don’t let it die by not addressing these issue and maintaining this radio silence when it comes to problems with Live TV/DVR. And if you are no longer developing Live TV/DVR - just let us know so we can cut our losses. I think the community would appreciate a response…

This issue also broadly discussed here

7

@chateaukok Agreed. I’m baffled by the lack of response from Plex. Very strange. @sa2000 can you help us get some attention from Plex devs? This is a major DVR issue considering recordings are failing.

8

@chateaukok I agree it seems to be an issue affecting a number of platforms. I follow the other thread as well, but since it was in the Shield forum I wasn’t sure if I should post separately here or not.

To further update my post, since I last rebooted, Plex Media Server has been working great for the past several days, and has no issues recording the one station I’m having issues with. I’m utterly stumped by what the problem can be since it seems totally sporadic and not easily reproducible. Since it’s been confined to just one channel recently, I’m wondering if it has something to do with signal strength issues. e.g. if signal strength briefly drops for some reason, Plex has a hard time handling that. Who knows…

9

Thinking back a bit I realized that I just started seeing this issue when Live TV was added to the Xbox One and I started using Plex on Xbox One to watch Live TV. I never had this issue before using Live TV. So my theory is that it has to do with watching Live TV from almost any client…watch Live TV, turn off Xbox One, turn Xbox One back on and tuning the previously watched channel no longer works. What’s weird is that the HDHomerun app can still tune the channel, but Plex can’t. Is everyone seeing this issue soon after watching Live TV from a Plex app? it seems there are a bunch of threads that are all related.

Can we get a Plex employee or Plex Ninja to take a look? @cayars or @johnm_ColaSC , can either of you help us investigate this issue? This major bug makes Live TV completely unusable.

2 Likes
10

I don’t have an Xbox One to test with but I do not experience an issue with either my Roku or Fire TV Stick. As soon as I exit out of the Live TV channel I am watching the tuner is immediately released by Plex. Screenshot below with Live TV session on my Roku at the top, as soon as I exit out of the Live TV session the 2nd screenshot below the Roku shows the tuner was released. I then switched to my Fire TV Stick and started a Live TV session of the same channel as shown in 3rd screenshot, then exited out of Live TV channel on the Fire Stick and the 4th screenshot shows the tuner being released again.

If you are shutting your XBox off try exiting from the Live TV session then shutting it off.

11

@johnm_ColaSC Interesting. This seems to happen for other users using the web app, shield, Android TV, and possibly others. Any chance you could test on one of these?

I always exit the Live TV session before turning off my Xbox. I don’t quit Plex though. I’m not sure that would make a difference, especially given others are seeing this in other apps including the web app which would quit when the browser is closed. My Xbox does stay on in a low power mode. Again, don’t know how that would be the issue though since this doesn’t appear to be isolated to a single client type.

I’m not 100% certain that the tuner was NOT released either. I never checked that. I can try to reproduce though and see if there’s a session that sticks, locking the tuner and the channel. Frankly it seems associated with the channel more than the tuner though.

Here are some logs from one of the times I encountered this issue. Maybe there’s something in there that could help. Logs are associated with getting the error message on 2/22 at about 5:45 AM. I think though, that the issue probably would’ve started the previous day at about the same time when I last used Live TV.

12

@mbarylski said:
Can we get a Plex employee or Plex Ninja to take a look? @cayars or @johnm_ColaSC , can either of you help us investigate this issue? This major bug makes Live TV completely unusable.

Sorry mbarylski, but I’m no longer a Ninja and not working with Plex any longer. No hard feelings, but it just didn’t work out well for either of us. I didn’t want you to think I was ignoring you so I wanted to comment. But as you can tell @johnm_ColaSC can/will help you just fine.

13

@johnm_ColaSC I’m still trying to reproduce this issue, but it doesn’t seem to occur on command. Seems it takes a day or 2 and then all of a sudden I’m not able to tune a channel. I keep trying different scenarios, but so far nothing immediately reproduces the issue.

14

@johnm_ColaSC I was able to reproduce this issue a few minutes ago (3/3 at 7:30 AM). I got the message again on the Xbox One and web app. Here’s a screen shot from the web app. It seems like it may only affect some channels. NBC (channel 4.1 for me) is an issue, but I’ve seen it on CBS too. I didn’t have any recordings going. I just tuned to Live TV last night and when I tried it this morning I got the message. The tuner isn’t locked either. Nothing shows up in the status screen on Plex. So the tuner gets released, but not the channel. Very strange.

iz3rbaoj6c4p.png750×415 253 KB

15

Only odd thing I am seeing in the logs is several repeated entries like below:
Mar 03, 2018 07:08:22.742 [13412] DEBUG - DVR:Schedule: Scheduled an active operation ‘Live TV - Session’ on tuner 1 between 2018-03-01 23:55:27 and 2018-03-03 07:13:22 on channel 5.1

Almost looks like session was active for the past 1 1/2 days.

16

@johnm_ColaSC said:
Only odd thing I am seeing in the logs is several repeated entries like below:
Mar 03, 2018 07:08:22.742 [13412] DEBUG - DVR:Schedule: Scheduled an active operation ‘Live TV - Session’ on tuner 1 between 2018-03-01 23:55:27 and 2018-03-03 07:13:22 on channel 5.1

Almost looks like session was active for the past 1 1/2 days.

That’s probably contributing to the problem. It might be letting go of the tuner, but the session stays active and channel stays locked. One other thing that I noticed last night when troubleshooting is that the error occurs after a maintenance task runs. It seems to be either the database backup or metadata refresh that could be causing the problem, because my settings are to do both every 3 days. And, the error only seems to occur every 3 days. I can’t find a way to force either of these tasks though, so I have to wait until Monday nights task runs to test my theory again Tuesday morning. Can you test by forcing a database backup after watching NBC?

17

You could potentially change the option to everyday, then change the time the nightly processes kick in.

18

@cayars said:
You cold potentially change the option to everyday, then change the time the nightly processes kick in.

Where’s the everyday option? Is that just unchecking the “Backup database every three days” box like this?

19

I was referring to trying to force this to happen for debug purposes.

You could uncheck that option and manually backup the DB ever week or so depending on how often you add/change library options or content.

20

@cayars Ok, is the manual backup that you’re referring to the same as when it’s run as a scheduled task? I’d like to run the backup on command to see if the automated backup is causing the error. So is there a backup command that I could run in Powershell or something so I could manually call the automated backup script?