Plex DVR, Trying to watch Live TV but get error "Unable to tune channel (no parts)"

General Discussions Plex Features
367

Thanks for the explanation. I will poke around to get more familiar with the log files but will wait for another update before making any radical changes.

As an additional data point to all on this thread I did remove the two “older” HDHomeRun tuners (Dual) from my DVR setup and am currently running only two of the Connect tuners and now things appear to be working OK. I know support for the Dual tuners used to work but also that, as I understand it, the way the Dual tuners work in some cases is that they need to connect with SiliconDust’s servers to get some additional information (metadata?) to work with some programs so perhaps this is the cause of some folks troubles as well?

In any evert thanks again for taking the time to explain the log file and how you go about looking for information within them.

Regards,

Jay

368

Hi all

Since this seems to still be a on again / off again issue I figured I would just include my strange data point.

I was building a brand new server with two different HDHR models as tuners. For whatever reason I was getting the “(no parts)” error right off the bat and random dead hanging sessions until I removed one of the two tuners (left the QUADRO). This was on 1.14.0 I believe, it’s only been a few weeks. In the past I’ve had two devices like this but they were both HDHR Primes and it worked flawlessly.

At any rate, for some folks above I see they’re trying to mix tuners… so if they’re still having trouble maybe try removing one?

All of these sessions were dead and hanging until a reboot.
dead%20sessions

Notice how the PRIME has only 3 tuners but "6 recording now"

tunermismatch
tunermismatch.PNG829×558 331 KB

369

I missed that you had two Duals. The Dual’s need HDHR setup installed on Linux/Windows and want/need avahi running somewhere which if you have a linux box it usually is. The Connects are the ones that phone home and can use the web to setup. I added my connect first then the dual but it almost always uses the dual first when recording.

371

I have been looking at your logs which show that you have 2 devices with 4 tuners.

HDHomeRun 10339178 (192.168.33.110) - tuners 0 and 1
HDHomeRun 10381F37 (192.168.33.129) - tuners 0 and 1

The logs do show an issue that is being corrected for the next release of Plex Media Server - it is a problem where after a failure, subsequent attempts to tune get failed with a Plex Tuner Service logged error of no tuners available

This happened for example sometime after starting a 70 minute recording of WGN Weekend News at Nine - Episode 12-29 at 20:55 on 29th. Just over 60 minutes into the recording, there was a transcoder failure. The tuner was not closed down after this error and all subsequent attempts to tune on this DVR (10381F37) were failing with this underlying error No available tuners found to start streaming channel

For the other DVR (DVR 10339178), the logs show repeated failures to tune transponders for tuner 1 between December 27th 21:55 and December 28th 22:29. A couple of tunes for this tuner on the 2nd January were successful.
eg

Dec 28, 2018 22:28:29.678 [3096] WARN - [E] transponder_streamer::start_channel. Tuning stransponder for channel triplet://0:1003:1 failed

Dec 28, 2018 22:29:30.706 [2732] WARN - [E] transponder_streamer::start_channel. Tuning stransponder for channel triplet://0:1003:1 failed

This i would suspect would be a tuner issue.

The fix for the problem of not closing down the tuner after an error should be fixed in the preview release available here Much faster (hopefully), smaller (mostly) and better PMS builds preview (1.15.0.573-123756e9a) (Note that this is pre beta) - the fix will be in the next beta / public releases

372

@sa2000,

Thanks for taking a deeper dive into my log files.

Since posing the logs you reviewed, I have removed the Dual HDHomeRun tuners and that seemed to resolve the problem BUT again a few nights ago, The Blacklist (Thursday 1/3/19 scheduled from 20:55-22:05) had trouble recording. I only captured 4 seconds of the show. I have attached an updated set of log files in the event you are interested/have time to review them.

Do you have any insight you can share regarding the order/preference that Plex utilizes the tuners? Similar to @pl_5309, I have two each of the older Dual and newer Connect tuners but Plex appears to use only the Dual tuners. Thinking that the older tuners were a possible cause for my troubles, I removed them from my configuration and that is when I was able to again successfully watch and record shows until my recent failure with The Blacklist.

Lastly, so I understand the nomenclature, would you clarify your comments in the last paragraph of your response beginning with “For the other DVR” Are you referring to each tuner as a separate DVR or each session being recorded as a DVR? From my perspective I consider all eight tuners (two Dual and two Connect) to be part associated with the same DVR and as far as I know only have one “DVR” configured in Plex.

Thanks again for taking the time to review and respond to my logs. As I am relative new to Plex this is all Greek to me but I am slowly beginning to understand the black box under the hood as I follow along with these comments while troubleshooting my recording issues.

Plex Media Server Logs_2019-01-04_01-17-21.zip (3.6 MB)

Regards,

Jay

373

At the time of the recording it logged it had zero free tuners

To recap - previously you had two older type Dual HDHomerun tuners ? They were showing in previous logs with these identifiers and IP Addresses

dvb#hdhr#0#271815032    HDHomeRun 10339178 (192.168.33.110) - 0,
dvb#hdhr#1#271815032    HDHomeRun 10339178 (192.168.33.110) - 1,

dvb#hdhr#0#272113463    HDHomeRun 10381F37 (192.168.33.129) - 0,
dvb#hdhr#1#272113463    HDHomeRun 10381F37 (192.168.33.129) - 1,

In the new logs - these still showed up in the Plex Tuner Service logs - 2nd January - but they were not being used

In the Plex Media Server logs the new HDHomerun Connect are showing with following details
192.168.33.113 HDHomeRun Connect 10439DDD
192.168.33.111 HDHomeRun Connect 104356D8

Could you get me a zip of the database - send it by private message - same web page as getting the logs but download the database.

Also would like to see logs after a restart of the server and a completed short recording

Thanks

Basically the issue was that when it came to wanting to record on Jan 03, 2019 at 20:55:00 these two episodes
WGN News at Nine - Episode 01-03' on channel 9.1.
and
The Blacklist - E1 - Dr. Hans Koehler' on channel 5.1
It thought there was only one free tuner

Recording failed. Please check your tuner or antenna
375

Same issue. Extract of Logs below.

DVR:Subscription: Starting a new rolling subscription for session efrpa25ho6kjgu2ac1e4s2k3 channel 1.
Jan 06, 2019 16:20:17.486 [0xb03feb40] DEBUG - Completed: [192.168.1.33:56037] 200 GET /tv.plex.providers.epg.onconnect:4/grid?type=1%2C4&sort=beginsAt&endsAt%3E=1546799400&beginsAt%3C=1546801200 (18 live) TLS GZIP 5915ms 34024 bytes (pipelined: 1)
Jan 06, 2019 16:20:20.026 [0xb0bffb40] DEBUG - handleStreamRead code 335544539: short read
Jan 06, 2019 16:20:20.035 [0xb0bffb40] DEBUG - Auth: authenticated user 1 as F1 10AE
Jan 06, 2019 16:20:20.036 [0x8eff5b40] DEBUG - Request: [192.168.1.33:56037 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=1&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (17 live) TLS GZIP Signed-in Token (F1 10AE)
Jan 06, 2019 16:20:20.040 [0x8eff5b40] DEBUG - Beginning read from two-way stream.
Jan 06, 2019 16:20:23.390 [0x8bbffb40] DEBUG - HTTP 200 response from GET http://127.0.0.1:36483/:/plugins/com.plexapp.agents.localmedia/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M2CkFydGlzdHMxMAptZWRpYV90eXBlNQpuczgKcGFyZW50SURzNTMKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL1ZhcmlvdXMlMjBBcnRpc3RzP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNAozOTYwczIKaWRyMAo_
Jan 06, 2019 16:20:23.391 [0x8bbffb40] DEBUG - [com.plexapp.agents.localmedia] HTTP reply status 200, with 600 bytes of content.
Jan 06, 2019 16:20:23.394 [0xb0bffb40] DEBUG - Completed: [127.0.0.1:57678] 200 GET /:/plugins/com.plexapp.agents.localmedia/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M2CkFydGlzdHMxMAptZWRpYV90eXBlNQpuczgKcGFyZW50SURzNTMKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL1ZhcmlvdXMlMjBBcnRpc3RzP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNAozOTYwczIKaWRyMAo_ (15 live) GZIP 19818ms 861 bytes
Jan 06, 2019 16:20:23.445 [0x8e7f4b40] DEBUG - Request: [127.0.0.1:57700 (Loopback)] GET /:/plugins/com.plexapp.agents.localmedia/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNAozOTYwczQKZGJpZGkwCnM3CnZlcnNpb25uczEwCnBhcmVudEdVSURuczgKcGFyZW50SURzNgpBcnRpc3RzMTAKbWVkaWFfdHlwZXM1Mwpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vVmFyaW91cyUyMEFydGlzdHM%40bGFuZz1lbnM0Cmd1aWRzNApudWxsczIKaWRyMAo_ (14 live) GZIP Signed-in Token (F1 10AE)
Jan 06, 2019 16:20:23.447 [0x8e7f4b40] DEBUG - [com.plexapp.agents.localmedia] Sending command over HTTP (GET): /:/plugins/com.plexapp.agents.localmedia/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNAozOTYwczQKZGJpZGkwCnM3CnZlcnNpb25uczEwCnBhcmVudEdVSURuczgKcGFyZW50SURzNgpBcnRpc3RzMTAKbWVkaWFfdHlwZXM1Mwpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vVmFyaW91cyUyMEFydGlzdHM%40bGFuZz1lbnM0Cmd1aWRzNApudWxsczIKaWRyMAo_
Jan 06, 2019 16:20:23.449 [0x8e7f4b40] DEBUG - HTTP requesting GET http://127.0.0.1:36483/:/plugins/com.plexapp.agents.localmedia/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNAozOTYwczQKZGJpZGkwCnM3CnZlcnNpb25uczEwCnBhcmVudEdVSURuczgKcGFyZW50SURzNgpBcnRpc3RzMTAKbWVkaWFfdHlwZXM1Mwpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vVmFyaW91cyUyMEFydGlzdHM%40bGFuZz1lbnM0Cmd1aWRzNApudWxsczIKaWRyMAo_
Jan 06, 2019 16:20:23.574 [0x9f7feb40] DEBUG - Request: [127.0.0.1:57704 (Loopback)] GET /library/metadata/3960/tree (14 live) GZIP Signed-in Token (F1 10AE)
Jan 06, 2019 16:20:23.671 [0x9f7feb40] DEBUG - It took 80.000000 ms to retrieve 185 items.
Jan 06, 2019 16:20:27.149 [0x8b3feb40] DEBUG - Activity: registered new activity ab1e54f1-2b89-4b1f-b600-a713cc924f32 - Processing subscriptions
Jan 06, 2019 16:20:27.159 [0x8b3feb40] DEBUG - DVR:Subscription: Scheduling subscriptions.
Jan 06, 2019 16:20:27.254 [0x8b3feb40] DEBUG - DVR:Subscription: Initialized 1 schedulers.
Jan 06, 2019 16:20:27.300 [0x8b3feb40] DEBUG - DVR:Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Jan 06, 2019 16:20:27.360 [0x8b3feb40] DEBUG - DVR:NewSchedule: Partitioning, step one, let's compute an absolute ordering.
Jan 06, 2019 16:20:27.360 [0x8b3feb40] DEBUG -  DVR:NewSchedule: Between 2019-01-06 16:20:16 and 2019-01-06 16:25:16 on channel 1: 'Live TV - Session'
Jan 06, 2019 16:20:27.360 [0x8b3feb40] DEBUG - DVR:NewSchedule: Initial partitioning completed with 1 partitions.
Jan 06, 2019 16:20:27.361 [0x8b3feb40] DEBUG - DVR:NewSchedule: Partitioning completed with 1 partitions.
Jan 06, 2019 16:20:27.361 [0x8b3feb40] DEBUG - DVR:NewSchedule: PARTITION of size 1.
Jan 06, 2019 16:20:27.361 [0x8b3feb40] DEBUG -  DVR:NewSchedule:    Between 2019-01-06 16:20:16 and 2019-01-06 16:25:16 on channel 1: 'Live TV - Session'
Jan 06, 2019 16:20:27.361 [0x8b3feb40] DEBUG - DVR:NewSchedule: After processing the partition, we have 1 alternate realities.
Jan 06, 2019 16:20:27.361 [0x8b3feb40] DEBUG - DVR:NewSchedule: We computed 1 alternative realities in 0 ms.
Jan 06, 2019 16:20:27.362 [0x8b3feb40] DEBUG - DVR:NewSchedule: The winning schedule had score: 10 and 0 conflicts.
Jan 06, 2019 16:20:27.362 [0x8b3feb40] DEBUG -  DVR:NewSchedule: device 0 tuner 0 airing 0 between 2019-01-06 16:20:16 and 2019-01-06 16:25:16 on channel 1: 'Live TV - Session'
Jan 06, 2019 16:20:27.378 [0x8b3feb40] DEBUG - DVR:Grabber: Starting grab immediately for 'Live TV - Session' on channel 1 and we're 11 seconds in already.
Jan 06, 2019 16:20:27.379 [0x8b3feb40] DEBUG - DVR:Subscription: Scheduled all subscriptions in 213 ms.
Jan 06, 2019 16:20:27.461 [0x8b3feb40] DEBUG - Activity: Ended activity ab1e54f1-2b89-4b1f-b600-a713cc924f32.
Jan 06, 2019 16:20:27.462 [0x8b3feb40] DEBUG - DVR:Subscription: There are 1 active grabs at the end.
Jan 06, 2019 16:20:27.462 [0x8b3feb40] DEBUG - DVR:Subscription: Waiting for media grab to start.
Jan 06, 2019 16:20:27.471 [0x91ffbb40] DEBUG - DVR:Grabber: Starting operation for 'Live TV - Session' on channel 1.
Jan 06, 2019 16:20:27.488 [0x91ffbb40] DEBUG - Activity: registered new activity ef54d62c-fc9f-4682-9762-d06c2e8d299d - Recording
Jan 06, 2019 16:20:27.511 [0x95bffb40] DEBUG - Activity: updated activity ef54d62c-fc9f-4682-9762-d06c2e8d299d - completed 3% - Recording
Jan 06, 2019 16:20:27.517 [0x91ffbb40] DEBUG - DVR:Grabber: Remote starting a media grab on device device://tv.plex.grabbers.tunerservice/dvb%23v4l%23%2fsys%2fdevices%2fpci0000%3a00%2f0000%3a00%3a04.1%2fusb1%2f1-2%2f1-2%3a1.0%3a0011403509 ( PCTV 292e, supported) tuner 0.
Jan 06, 2019 16:20:27.517 [0x91ffbb40] DEBUG - DVR:Grabber: Creating custom segmented recorder for grab for channel 1
Jan 06, 2019 16:20:27.882 [0x91ffbb40] DEBUG - DVR:Segmenter: Creating a new recorder for http://127.0.0.1:32600/devices/dvb%23v4l%230%23%2fsys%2fdevices%2fpci0000%3a00%2f0000%3a00%3a04.1%2fusb1%2f1-2%2f1-2%3a1.0/media/triplet%3A%2F%2F9018%3A4175%3A4175?param=%3C%3Fxml%20version%3D%221%2E0%22%20encoding%3D%22UTF-8%22%3F%3E%0A%3Ctunedata%3E%3Ctt%3E4%3C%2Ftt%3E%3Cdq%3E0%3C%2Fdq%3E%3Cdr%2F%3E%3Cfr%3E481833%3C%2Ffr%3E%3Cmd%3E0%3C%2Fmd%3E%3Csr%3E0%3C%2Fsr%3E%3Cpl%3E0%3C%2Fpl%3E%3Clf%3E8%3C%2Flf%3E%3Cl1%3E0%3C%2Fl1%3E%3Cl2%3E0%3C%2Fl2%3E%3Cls%3E0%3C%2Fls%3E%3Cpp%3E0%3C%2Fpp%3E%3Chz%3E0%3C%2Fhz%3E%3Cin%3E0%3C%2Fin%3E%3Cfc%3E0%3C%2Ffc%3E%3Ct%3E4175%3C%2Ft%3E%3Cn%3E9018%3C%2Fn%3E%3Cs%3E4175%3C%2Fs%3E%3Cen%3E0%3C%2Fen%3E%3C%2Ftunedata%3E%0A.
Jan 06, 2019 16:20:28.197 [0x91ffbb40] DEBUG - DVR:Grabber: Setting timer to stop grab of 'Live TV - Session' on channel 1 in 288 seconds.
Jan 06, 2019 16:20:28.198 [0x91ffbb40] DEBUG - DVR:Grabber: Waiting for a tuner (1 available).
Jan 06, 2019 16:20:28.198 [0x91ffbb40] DEBUG - DVR:Grabber: Allocated a tuner (0 left)
Jan 06, 2019 16:20:28.362 [0x91ffbb40] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 06, 2019 16:20:28.362 [0x91ffbb40] DEBUG - Selecting best audio stream for part ID -1 (autoselect: 0 language: en)
Jan 06, 2019 16:20:28.362 [0x91ffbb40] DEBUG - Audio Stream: -1, Subtitle Stream: -1
Jan 06, 2019 16:20:28.911 [0x91ffbb40] DEBUG - MDE: Selected protocol hls; container: mpegts
Jan 06, 2019 16:20:28.911 [0x91ffbb40] ERROR - Unable to find title for item of type 5
Jan 06, 2019 16:20:28.912 [0x91ffbb40] DEBUG - MDE: analyzing media item -1
Jan 06, 2019 16:20:28.912 [0x91ffbb40] DEBUG - MDE: : no direct play video profile exists for http/mpegts/
Jan 06, 2019 16:20:28.912 [0x91ffbb40] DEBUG - MDE: : no direct play video profile exists for http/mpegts//
Jan 06, 2019 16:20:28.912 [0x91ffbb40] DEBUG - MDE: : codec is unavailable for analysis
Jan 06, 2019 16:20:28.913 [0x91ffbb40] DEBUG - MDE: : codec is unavailable for analysis
Jan 06, 2019 16:20:28.913 [0x91ffbb40] ERROR - Unable to find title for item of type 5
Jan 06, 2019 16:20:28.913 [0x91ffbb40] DEBUG - MDE: : selected media 0 / -1
Jan 06, 2019 16:20:28.926 [0x91ffbb40] DEBUG - Cleaning directory for session c85b7f34-6223-409c-91a9-34c797e9e433 ()
Jan 06, 2019 16:20:29.041 [0x91ffbb40] DEBUG - Starting a transcode session c85b7f34-6223-409c-91a9-34c797e9e433 at offset -1.0 (state=3)
Jan 06, 2019 16:20:29.065 [0x91ffbb40] DEBUG - Streaming Resource: Added session 0xaeeefc14:c85b7f34-6223-409c-91a9-34c797e9e433
Jan 06, 2019 16:20:29.105 [0x91ffbb40] DEBUG - Job running: EAE_ROOT='/tmp/pms-5305b46b-668f-4afb-a727-512c169ea415/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/var/lib/plexmediaserver/Library/Application\ Support/Plex\ Media\ Server/Codecs/531e313-1328-linux-ubuntu-i686/' XDG_CACHE_HOME='/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache' XDG_DATA_HOME='/usr/lib/plexmediaserver/Resources' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/usr/lib/plexmediaserver/Plex Transcoder' '-noaccurate_seek' '-ignore_unknown' '-scan_all_pmts' '-1' '-rw_timeout' '30000000' '-fflags' '+discardcorruptts+fillwallclockdts' '-probesize' '10000000' '-i' 'http://127.0.0.1:32600/devices/dvb%23v4l%230%23%2fsys%2fdevices%2fpci0000%3a00%2f0000%3a00%3a04.1%2fusb1%2f1-2%2f1-2%3a1.0/media/triplet%3A%2F%2F9018%3A4175%3A4175?param=%3C%3Fxml%20version%3D%221%2E0%22%20encoding%3D%22UTF-8%22%3F%3E%0A%3Ctunedata%3E%3Ctt%3E4%3C%2Ftt%3E%3Cdq%3E0%3C%2Fdq%3E%3Cdr%2F%3E%3Cfr%3E481833%3C%2Ffr%3E%3Cmd%3E0%3C%2Fmd%3E%3Csr%3E0%3C%2Fsr%3E%3Cpl%3E0%3C%2Fpl%3E%3Clf%3E8%3C%2Flf%3E%3Cl1%3E0%3C%2Fl1%3E%3Cl2%3E0%3C%2Fl2%3E%3Cls%3E0%3C%2Fls%3E%3Cpp%3E0%3C%2Fpp%3E%3Chz%3E0%3C%2Fhz%3E%3Cin%3E0%3C%2Fin%3E%3Cfc%3E0%3C%2Ffc%3E%3Ct%3E4175%3C%2Ft%3E%3Cn%3E9018%3C%2Fn%3E%3Cs%3E4175%3C%2Fs%3E%3Cen%3E0%3C%2Fen%3E%3C%2Ftunedata%3E%0A' '-map' '0:V?' '-codec:V' 'copy' '-map' '0:a?' '-codec:a' 'copy' '-copypriorss:a' '0' '-map' '0:s?' '-codec:s' 'copy' '-segment_format' 'mpegts' '-f' 'ssegment' '-individual_header_trailer' '0' '-segment_time' '1' '-segment_start_number' '0' '-segment_time_delta' '0.25' '-segment_list' 'http://127.0.0.1:32400/video/:/transcode/session/c85b7f34-6223-409c-91a9-34c797e9e433/5a9dc848-de21-4925-9ec1-e347fd160d55/seglist' '-segment_list_type' 'csv' '-segment_list_size' '2147483647' '-segment_list_separate_stream_times' '1' '-max_delay' '5000000' '-map_metadata' '-1' '-map_chapters' '-1' 'media-%05d.ts' '-y' '-nostats' '-loglevel' 'quiet' '-loglevel_plex' 'error' '-xioerror' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/c85b7f34-6223-409c-91a9-34c797e9e433/5a9dc848-de21-4925-9ec1-e347fd160d55/progress'
Jan 06, 2019 16:20:29.191 [0x91ffbb40] DEBUG - Jobs: Starting child process with pid 7650
Jan 06, 2019 16:20:29.784 [0x9f7feb40] DEBUG - It took 4420.000000 ms to retrieve 6113 items.
Jan 06, 2019 16:20:30.515 [0xa0ffeb40] DEBUG - Activity: updated activity ef54d62c-fc9f-4682-9762-d06c2e8d299d - completed 4% - Recording
Jan 06, 2019 16:20:31.783 [0x8a3fcb40] DEBUG - Request: [127.0.0.1:57718 (Loopback)] PUT /video/:/transcode/session/c85b7f34-6223-409c-91a9-34c797e9e433/5a9dc848-de21-4925-9ec1-e347fd160d55/progress?status=startup (13 live) Signed-in Token (F1 10AE)
Jan 06, 2019 16:20:31.787 [0xb03feb40] DEBUG - Completed: [127.0.0.1:57718] 204 PUT /video/:/transcode/session/c85b7f34-6223-409c-91a9-34c797e9e433/5a9dc848-de21-4925-9ec1-e347fd160d55/progress?status=startup (13 live) 4ms 203 bytes (pipelined: 1) (range: bytes=0-) 
Jan 06, 2019 16:20:31.789 [0x8bbffb40] DEBUG - Request: [127.0.0.1:57718 (Loopback)] PUT /video/:/transcode/session/c85b7f34-6223-409c-91a9-34c797e9e433/5a9dc848-de21-4925-9ec1-e347fd160d55/progress?status=opening (13 live) Signed-in Token (F1 10AE)
Jan 06, 2019 16:20:31.793 [0xb03feb40] DEBUG - Completed: [127.0.0.1:57718] 204 PUT /video/:/transcode/session/c85b7f34-6223-409c-91a9-34c797e9e433/5a9dc848-de21-4925-9ec1-e347fd160d55/progress?status=opening (13 live) 3ms 203 bytes (pipelined: 2) (range: bytes=0-) 
Jan 06, 2019 16:20:33.516 [0x9e7ffb40] DEBUG - Activity: updated activity ef54d62c-fc9f-4682-9762-d06c2e8d299d - completed 5% - Recording
Jan 06, 2019 16:20:34.029 [0xb03feb40] DEBUG - Completed: [127.0.0.1:57704] 200 GET /library/metadata/3960/tree (13 live) GZIP 10455ms 634532 bytes
Jan 06, 2019 16:20:35.312 [0x937feb40] DEBUG - Statistics: Flushing 14 expired bandwidth entries, 0 expired media entries.
Jan 06, 2019 16:20:36.517 [0x9e7ffb40] DEBUG - Activity: updated activity ef54d62c-fc9f-4682-9762-d06c2e8d299d - completed 6% - Recording
Jan 06, 2019 16:20:39.518 [0x937feb40] DEBUG - Activity: updated activity ef54d62c-fc9f-4682-9762-d06c2e8d299d - completed 7% - Recording
Jan 06, 2019 16:20:40.041 [0xb0bffb40] DEBUG - handleStreamRead code 335544539: short read
Jan 06, 2019 16:20:40.078 [0xb03feb40] DEBUG - Auth: authenticated user 1 as F1 10AE
Jan 06, 2019 16:20:40.081 [0x8a3fcb40] DEBUG - Request: [192.168.1.33:56059 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=1&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (13 live) TLS GZIP Signed-in Token (F1 10AE)
Jan 06, 2019 16:20:40.084 [0x8a3fcb40] DEBUG - Beginning read from two-way stream.
Jan 06, 2019 16:20:42.519 [0x97fffb40] DEBUG - Activity: updated activity ef54d62c-fc9f-4682-9762-d06c2e8d299d - completed 8% - Recording
Jan 06, 2019 16:20:45.223 [0xac3ffb40] DEBUG - It took 11680.000000 ms to retrieve 200 items.
Jan 06, 2019 16:20:45.523 [0x95bffb40] DEBUG - Activity: updated activity ef54d62c-fc9f-4682-9762-d06c2e8d299d - completed 9% - Recording
Jan 06, 2019 16:20:46.133 [0xac3ffb40] WARN - SLOW QUERY: It took 380.000000 ms to retrieve 50 items.
Jan 06, 2019 16:20:46.707 [0xac3ffb40] DEBUG - HubCache: Computed '1/home.television.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' in 33670 ms.
Jan 06, 2019 16:20:46.709 [0xac3ffb40] DEBUG - HubCache: Adding '1/home.videos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.videos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10).
Jan 06, 2019 16:20:46.740 [0xac3ffb40] DEBUG - HubCache: Computed '1/home.videos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' in 19 ms.
Jan 06, 2019 16:20:46.748 [0xac3ffb40] DEBUG - HubCache: Adding '1/home.photos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.photos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10).
Jan 06, 2019 16:20:46.771 [0xac3ffb40] DEBUG - HubCache: Computed '1/home.photos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' in 16 ms.
Jan 06, 2019 16:20:46.772 [0xac3ffb40] DEBUG - HubCache: Adding '1/home.music.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.music.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10).
Jan 06, 2019 16:20:46.941 [0xac3ffb40] DEBUG - HubCache: Computed '1/home.music.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' in 168 ms.
Jan 06, 2019 16:20:47.048 [0xac3ffb40] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 06, 2019 16:20:47.049 [0xac3ffb40] DEBUG - Selecting best audio stream for part ID 23749 (autoselect: 0 language: en)
Jan 06, 2019 16:20:47.049 [0xac3ffb40] DEBUG - Audio Stream: 39658, Subtitle Stream: -1
Jan 06, 2019 16:20:47.079 [0xac3ffb40] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 06, 2019 16:20:47.080 [0xac3ffb40] DEBUG - Selecting best audio stream for part ID 23737 (autoselect: 0 language: en)
Jan 06, 2019 16:20:47.081 [0xac3ffb40] DEBUG - Audio Stream: 26485, Subtitle Stream: -1
Jan 06, 2019 16:20:47.088 [0xac3ffb40] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 06, 2019 16:20:47.089 [0xac3ffb40] DEBUG - Selecting best audio stream for part ID 48 (autoselect: 0 language: en)
Jan 06, 2019 16:20:47.089 [0xac3ffb40] DEBUG - Audio Stream: 150, Subtitle Stream: -1
Jan 06, 2019 16:20:47.128 [0xac3ffb40] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 06, 2019 16:20:47.129 [0xac3ffb40] DEBUG - Selecting best audio stream for part ID 152 (autoselect: 0 language: en)
Jan 06, 2019 16:20:47.129 [0xac3ffb40] DEBUG - Audio Stream: 658, Subtitle Stream: -1
Jan 06, 2019 16:20:47.134 [0xac3ffb40] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 06, 2019 16:20:47.137 [0xac3ffb40] DEBUG - Selecting best audio stream for part ID 151 (autoselect: 0 language: en)
Jan 06, 2019 16:20:47.137 [0xac3ffb40] DEBUG - Audio Stream: 656, Subtitle Stream: -1
Jan 06, 2019 16:20:47.146 [0xac3ffb40] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 06, 2019 16:20:47.148 [0xac3ffb40] DEBUG - Selecting best audio stream for part ID 214 (autoselect: 0 language: en)
Jan 06, 2019 16:20:47.148 [0xac3ffb40] DEBUG - Audio Stream: 881, Subtitle Stream: -1
Jan 06, 2019 16:20:47.156 [0xac3ffb40] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 06, 2019 16:20:47.164 [0xac3ffb40] DEBUG - Selecting best audio stream for part ID 213 (autoselect: 0 language: en)
Jan 06, 2019 16:20:47.165 [0xac3ffb40] DEBUG - Audio Stream: 879, Subtitle Stream: -1
Jan 06, 2019 16:20:47.170 [0xac3ffb40] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 06, 2019 16:20:47.170 [0xac3ffb40] DEBUG - Selecting best audio stream for part ID 212 (autoselect: 0 language: en)
Jan 06, 2019 16:20:47.171 [0xac3ffb40] DEBUG - Audio Stream: 877, Subtitle Stream: -1
Jan 06, 2019 16:20:47.191 [0xac3ffb40] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 06, 2019 16:20:47.192 [0xac3ffb40] DEBUG - Selecting best audio stream for part ID 211 (autoselect: 0 language: en)
Jan 06, 2019 16:20:47.193 [0xac3ffb40] DEBUG - Audio Stream: 875, Subtitle Stream: -1
Jan 06, 2019 16:20:47.198 [0xac3ffb40] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 06, 2019 16:20:47.199 [0xac3ffb40] DEBUG - Selecting best audio stream for part ID 505 (autoselect: 0 language: en)
Jan 06, 2019 16:20:47.199 [0xac3ffb40] DEBUG - Audio Stream: 1632, Subtitle Stream: -1
Jan 06, 2019 16:20:47.209 [0xac3ffb40] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 06, 2019 16:20:47.210 [0xac3ffb40] DEBUG - Selecting best audio stream for part ID 504 (autoselect: 0 language: en)
Jan 06, 2019 16:20:47.210 [0xac3ffb40] DEBUG - Audio Stream: 1630, Subtitle Stream: -1
Jan 06, 2019 16:20:47.215 [0xac3ffb40] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 06, 2019 16:20:47.216 [0xac3ffb40] DEBUG - Selecting best audio stream for part ID 503 (autoselect: 0 language: en)
Jan 06, 2019 16:20:47.216 [0xac3ffb40] DEBUG - Audio Stream: 1628, Subtitle Stream: -1
Jan 06, 2019 16:20:47.224 [0xac3ffb40] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 06, 2019 16:20:47.225 [0xac3ffb40] DEBUG - Selecting best audio stream for part ID 355 (autoselect: 0 language: en)
Jan 06, 2019 16:20:47.225 [0xac3ffb40] DEBUG - Audio Stream: 1262, Subtitle Stream: -1
Jan 06, 2019 16:20:47.270 [0xac3ffb40] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 06, 2019 16:20:47.270 [0xac3ffb40] DEBUG - Selecting best audio stream for part ID 502 (autoselect: 0 language: en)
Jan 06, 2019 16:20:47.271 [0xac3ffb40] DEBUG - Audio Stream: 1626, Subtitle Stream: -1
Jan 06, 2019 16:20:47.400 [0xb03feb40] DEBUG - Completed after connection close: [192.168.1.33:56031] 200 GET /hubs?excludeFields=summary&count=16&includeEmpty=1&includeFeaturedTags=1&includeStations=1&includeExternalMetadata=1&excludePlaylists=1&onlyTransient=1 (12 live) TLS GZIP 37500ms 17 bytes (pipelined: 1)
Jan 06, 2019 16:20:48.524 [0xa17ffb40] DEBUG - Activity: updated activity ef54d62c-fc9f-4682-9762-d06c2e8d299d - completed 10% - Recording
Jan 06, 2019 16:20:51.526 [0x97fffb40] DEBUG - Activity: updated activity ef54d62c-fc9f-4682-9762-d06c2e8d299d - completed 11% - Recording
Jan 06, 2019 16:20:54.204 [0x91ffbb40] DEBUG - TranscodeSession: timed out waiting to find duration for live session
Jan 06, 2019 16:20:54.204 [0x91ffbb40] ERROR - Failed to start session.
Jan 06, 2019 16:20:54.254 [0x91ffbb40] DEBUG - Streaming Resource: Terminated session 0xaeeefc14:c85b7f34-6223-409c-91a9-34c797e9e433 with reason Recording failed. Please check your tuner or antenna.
Jan 06, 2019 16:20:54.255 [0x91ffbb40] ERROR - DVR:Recorder: Unable to create transcode session or session failed to start.
Jan 06, 2019 16:20:54.255 [0xa0ffeb40] DEBUG - Killing job.
Jan 06, 2019 16:20:54.255 [0xa0ffeb40] DEBUG - Signalling job ID 7650 with 9
Jan 06, 2019 16:20:54.255 [0xa0ffeb40] DEBUG - Job was already killed, not killing again.
Jan 06, 2019 16:20:54.255 [0x91ffbb40] DEBUG - Job was already killed, not killing again.
Jan 06, 2019 16:20:54.255 [0xa0ffeb40] DEBUG - Stopping transcode session c85b7f34-6223-409c-91a9-34c797e9e433
Jan 06, 2019 16:20:54.256 [0x91ffbb40] ERROR - DVR:Recorder: Error 16 (Recording failed. Please check your tuner or antenna.) starting the record, shutting things down.
Jan 06, 2019 16:20:54.256 [0x91ffbb40] DEBUG - DVR:Grabber: Cleaning up rolling grab for channel 1.
Jan 06, 2019 16:20:54.256 [0x91ffbb40] DEBUG - DVR:Recorder: No more consumers, stopping.
Jan 06, 2019 16:20:54.260 [0xa7ffeb40] DEBUG - Cleaning directory for session c85b7f34-6223-409c-91a9-34c797e9e433 (/home/matt/Downloads/PlexTempTranscode/Transcode/Sessions/plex-transcode-c85b7f34-6223-409c-91a9-34c797e9e433)
Jan 06, 2019 16:20:54.283 [0xaafffb40] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Transcoder' exit code for process 7650 is -9 (signal: Killed)
Jan 06, 2019 16:20:54.283 [0x91ffbb40] DEBUG - DVR:Grabber: Freed a tuner (now 1 available)
Jan 06, 2019 16:20:54.284 [0x91ffbb40] DEBUG - DVR:Recorder: Asked to stop recording 'Live TV - Session' on channel 1.
Jan 06, 2019 16:20:54.284 [0x91ffbb40] DEBUG - DVR:Grabber: Operation for 'Live TV - Session' on channel 1 completed with status error (Recording failed. Please check your tuner or antenna.)
Jan 06, 2019 16:20:54.284 [0x8b3feb40] ERROR - DVR:Subscription: Rolling media grab failed to start.
Jan 06, 2019 16:20:54.284 [0x8b3feb40] DEBUG - DVR:Subscription: Cancelling grab for subscription 15: 'Live TV - Session' on channel 1
Jan 06, 2019 16:20:54.285 [0x8b3feb40] DEBUG - DVR:Grabber: Cancelling operation for 'Live TV - Session' on channel 1 with status cancelled.
Jan 06, 2019 16:20:54.285 [0x8b3feb40] DEBUG - DVR:Grabber: Operation was active, stopping recorder.
Jan 06, 2019 16:20:54.286 [0x8b3feb40] DEBUG - DVR:Grabber: We've cancelled the grab with 262 seconds remaining.
Jan 06, 2019 16:20:54.286 [0x8b3feb40] DEBUG - DVR:Grabber: Cleaning up rolling grab for channel 1.
Jan 06, 2019 16:20:54.287 [0x8b3feb40] DEBUG - DVR:Recorder: No more consumers, stopping.
Jan 06, 2019 16:20:54.312 [0x91ffbb40] DEBUG - Activity: Ended activity ef54d62c-fc9f-4682-9762-d06c2e8d299d.
Jan 06, 2019 16:20:54.313 [0x91ffbb40] DEBUG - DVR:Grabber: Cleaning up rolling grab for channel 1.
Jan 06, 2019 16:20:54.313 [0x91ffbb40] DEBUG - DVR:Recorder: No more consumers, stopping.
Jan 06, 2019 16:20:54.313 [0x91ffbb40] ERROR - DVR:Grabber: Starting media grab failed.
Jan 06, 2019 16:20:54.314 [0x91ffbb40] ERROR - Unknown metadata type: 
Jan 06, 2019 16:20:54.314 [0x91ffbb40] DEBUG - Asked for unknown type: -1
Jan 06, 2019 16:20:54.389 [0xa0ffeb40] DEBUG - Transcoder: Cleaning old transcode directories.
Jan 06, 2019 16:20:54.397 [0xa0ffeb40] DEBUG - Whacked session c85b7f34-6223-409c-91a9-34c797e9e433, 0 remaining.
Jan 06, 2019 16:20:54.552 [0x8b3feb40] DEBUG - Activity: registered new activity 59d52bff-f748-4343-ae05-4630e73dac74 - Processing subscriptions
Jan 06, 2019 16:20:54.561 [0x8b3feb40] DEBUG - DVR:Subscription: Scheduling subscriptions.
Jan 06, 2019 16:20:54.662 [0x8b3feb40] DEBUG - DVR:Subscription: Initialized 1 schedulers.
Jan 06, 2019 16:20:54.665 [0x8b3feb40] DEBUG - DVR:Subscription: Starting with 1 grabs, scheduled 0 active ones preemptively.
Jan 06, 2019 16:20:54.665 [0x8b3feb40] DEBUG - DVR:NewSchedule: Partitioning, step one, let's compute an absolute ordering.
Jan 06, 2019 16:20:54.671 [0x8b3feb40] DEBUG - DVR:NewSchedule: Initial partitioning completed with 0 partitions.
Jan 06, 2019 16:20:54.672 [0x8b3feb40] DEBUG - DVR:NewSchedule: Partitioning completed with 0 partitions.
Jan 06, 2019 16:20:54.672 [0x8b3feb40] DEBUG - DVR:NewSchedule: We computed 1 alternative realities in 0 ms.
Jan 06, 2019 16:20:54.673 [0x8b3feb40] DEBUG - DVR:NewSchedule: The winning schedule had score: 0 and 0 conflicts.
Jan 06, 2019 16:20:54.674 [0x8b3feb40] DEBUG - DVR:Subscription: Scheduled all subscriptions in 112 ms.
Jan 06, 2019 16:20:54.674 [0x8b3feb40] DEBUG - Activity: Ended activity 59d52bff-f748-4343-ae05-4630e73dac74.
Jan 06, 2019 16:20:54.678 [0x8b3feb40] DEBUG - DVR:Subscription: There are 0 active grabs at the end.
Jan 06, 2019 16:20:54.692 [0xb0bffb40] DEBUG - Completed after connection close: [192.168.1.33:56034] 200 POST /livetv/dvrs/4/channels/1/tune (11 live) TLS GZIP 39287ms 17 bytes (pipelined: 3)
Jan 06, 2019 16:20:55.213 [0x8abfdb40] DEBUG - DVR:Segmenter: Stopping idle session.
Jan 06, 2019 16:20:55.264 [0xb0bffb40] DEBUG - Auth: authenticated user 1 as F1 10AE
Jan 06, 2019 16:20:55.270 [0x8bbffb40] DEBUG - Request: [192.168.1.33:56071 (Subnet)] GET /:/prefs (13 live) TLS GZIP Signed-in Token (F1 10AE)
Jan 06, 2019 16:20:55.273 [0xb03feb40] DEBUG - Auth: authenticated user 1 as F1 10AE
Jan 06, 2019 16:20:55.277 [0xac3ffb40] DEBUG - Request: [192.168.1.33:56072 (Subnet)] GET /accounts/1 (14 live) TLS GZIP Signed-in Token (F1 10AE)
Jan 06, 2019 16:20:55.295 [0xb03feb40] DEBUG - Auth: authenticated user 1 as F1 10AE
Jan 06, 2019 16:20:55.296 [0xb0bffb40] DEBUG - Auth: authenticated user 1 as F1 10AE
Jan 06, 2019 16:20:55.298 [0x8b3feb40] DEBUG - Request: [192.168.1.33:56074 (Subnet)] GET /system/:/prefs (13 live) TLS GZIP Signed-in Token (F1 10AE)
Jan 06, 2019 16:20:55.305 [0x8b3feb40] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs
Jan 06, 2019 16:20:55.306 [0x8b3feb40] DEBUG - HTTP requesting GET http://127.0.0.1:33757/system/:/prefs
Jan 06, 2019 16:20:55.309 [0xac3ffb40] DEBUG - Request: [192.168.1.33:56073 (Subnet)] GET /myplex/account (13 live) TLS GZIP Signed-in Token (F1 10AE)
Jan 06, 2019 16:20:55.328 [0xb03feb40] DEBUG - Completed: [192.168.1.33:56072] 200 GET /accounts/1 (13 live) TLS GZIP 51ms 592 bytes (pipelined: 1)
Jan 06, 2019 16:20:55.329 [0xb0bffb40] DEBUG - Completed: [192.168.1.33:56073] 200 GET /myplex/account (13 live) TLS GZIP 19ms 2129 bytes (pipelined: 1)
Jan 06, 2019 16:20:55.398 [0xb0bffb40] DEBUG - Completed: [192.168.1.33:56071] 200 GET /:/prefs (13 live) TLS GZIP 128ms 7236 bytes (pipelined: 1)
Jan 06, 2019 16:20:55.675 [0x8b3feb40] DEBUG - HTTP 304 response from GET http://127.0.0.1:33757/system/:/prefs
Jan 06, 2019 16:20:55.678 [0x8b3feb40] DEBUG - [com.plexapp.system] HTTP reply status 304, with 0 bytes of content.
Jan 06, 2019 16:20:55.760 [0xb0bffb40] DEBUG - Completed: [192.168.1.33:56074] 304 GET /system/:/prefs (13 live) TLS GZIP 464ms 397 bytes (pipelined: 1)
Jan 06, 2019 16:20:57.022 [0x97fffb40] DEBUG - EPG[onconnect]: Purging 0 airings which completed in the past.
Jan 06, 2019 16:20:58.268 [0x91ffbb40] DEBUG - Checking if time for scheduled update
Jan 06, 2019 16:20:58.443 [0xb03feb40] DEBUG - Auth: authenticated user 1 as F1 10AE
Jan 06, 2019 16:20:58.445 [0x8abfdb40] DEBUG - Request: [192.168.1.33:56081 (Subnet)] GET /diagnostics/logs (14 live) TLS GZIP Signed-in Token (F1 10AE)
Jan 06, 2019 16:20:58.466 [0x8abfdb40] DEBUG - Diagnostics: Building logfile zip
376

my hdhomerun works with plex via browser like a charm but when looking at the console data when I use my appletv Its all geared towards a loopback address. could this be the issue?

377

please attach full logs zip and summary of the problem and what time

I cannot investigate a problem from a pasted log extract

378

If there is a problem and specifically when using the Plex for Apple TV app, please attach server logs and Plex for Apple TV log

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

and for Apple TV logs obtained through browser and saved to text file
See
https://support.plex.tv/articles/212639598-apple-tv-logs/

379

Plex Media Server Logs_2019-01-06_14-26-58.zip (3.0 MB)

Thanks for responding with the instructions. I’ve attached the logs.

380

Plex Media Server Logs_2019-01-07_08-13-20.zip (4.2 MB)

Same issue going on here, please see logs from around 08:12 07/01/19. I can’t get any live channel to play.

Running 1.14.1.5488-cc260c476 on Lubuntu

381

What is the actual problem and at what times?

I see you requested livetv channel 4.1 at 14:17 on the Apple TV and then switched to channel 6.1 at 14:18 and that was stopped at 14:22

You did not include the Plex for Apple TV log i asked for in my post Plex DVR, Trying to watch Live TV but get error "Unable to tune channel (no parts)" - #378 by sa2000

could we get some new logs from both server and apple tv app - only this time increase the level of logging of the server to verbose - but as logs fill quickly with verbose, need to make sure you capture the logs very soon after the problem arising and after you download the server logs, then download the Plex for Apple TV logs through a browser http://192.168.1.89:32500/logging whilst the Plex app is open on the Apple tv and copy and paste the displayed log to a text file

For verbose server logging, go to server settings, general, show advanced and select both debug and verbose. Do that first, restart the server to get fresh logs and then try to reproduce the problem and then when the problem arises, note down the time and also i need to know what the actual issue is. Then download the server logs and after that the Plex for Apple TV logs

Attach zip of server logs and the apple tv app log text file and any photos of the screen and description of what the issue was and at what time

After you get the evidence you can disable verbose logging leaving debug on

382

Thanks for the logs

The problem appears to be to do with communicating with the tuners.

Jan 07, 2019 08:11:47.183 [0xb6e28d40] INFO - [I] web_server_t::start. Starting web server on port 32600
Jan 07, 2019 08:12:25.138 [0xb4afcb40] INFO - [I] web_service_t::process_start_channel (device dvb#v4l#0#/sys/devices/pci0000:00/0000:00:04.1/usb1/1-2/1-2:1.0, channel triplet://9018:4175:4175)
Jan 07, 2019 08:12:25.139 [0xb4afcb40] INFO - [I] v4l::DeviceGetList_impl. getting v4l device list

Prior to that there were repeated errors when asking for status

Jan 04, 2019 17:29:57.823 [0xb5afeb40] INFO - [I] v4l::DeviceGetList_impl. Successfully opened frontend /dev/dvb/adapter0/frontend0
Jan 04, 2019 17:29:57.862 [0xb5afeb40] WARN - [E] web_service_t::get_device_status. get_device_info returned error for device dvb#v4l#0#/sys/devices/pci0000:00/0000:00:04.1/usb1/1-2/1-2:1.0

Jan 04, 2019 17:29:57.862 [0xb5afeb40] WARN - [E] web_service_t::process_start_channel. get_device_status failed for device dvb#v4l#0#/sys/devices/pci0000:00/0000:00:04.1/usb1/1-2/1-2:1.0

Do you have up to date firmware ?

Has it ever worked ?

Is it one of the supported models?
https://support.plex.tv/articles/225877427-supported-dvr-tuners-and-antennas/

383

Hi - Could you also check Syslog for any errors near the time of the log extracts I pasted

384

Getting the “unable to evalutate play request, could not tune channel. please check your tuner or antenna’ Code -1.” From both my ipad and iphone. Tuner is a Hauppauge WinTV dual HD which tunes the channel just fine and had been working fine until a few weeks ago. I tried deleting and re-adding into Plex but no joy. Same message. logs attachedPlex Media Server Logs_2019-01-13_18-39-18.zip (5.2 MB)

385

I’ve noticed, using a HDHR Prime that the network channels (ABC/NBC/CBS) are received as MPEG2 and PMS stops playing them after a few minutes producing “weak signal” error. All other channels play fine.
fyi I’m running PMS on Qnap TS251+ and watching on Roku on TLC TV
Is there a way to get PMS to play live channels that arrive as MPEG2 without choking?

Thanks

386

In order to fix the tuner/DVR issue, I just rolled Plex back to the prior version (1.14.0.5470). Works PERFECTLY again with my 2 HD Homerun boxes (6 tuners total).

Seems like a better idea than installing a super experimental (pre-beta) version.

To roll back, simply follow these instructions: I need to rollback from the latest Plex update

387

I rolled back to (1.14.0.5470), but still get same issue. After clean reboot, I can tune the first 2 channels. The third I try, I get the Playback Error “unable to tune channel”.

Im running on Win10 using the Firefox browser. Start live play on one channel, watch for a minute or two then stop it and select another channel. Third time fails. Logs are attached.
error at Jan 24, 2019 17:49

Plex Media Server Logs_2019-01-24_17-49-59.zip (1.8 MB)

388

I am also having the same issue where I cannot tune all my channels. Running version 1.14.1.5488. I have problems tuning all channels except for FOX.