In the past I was able to record/watch OTA shows in Plex but at some point it stopped working. Currently I get the “unable to tune channel” error when trying to watch or record. It doesn’t seem like it’s saving anything in the Cache/Transcode/Sessions// directory. Permissions seem correct.
Plex: 1.12.3.4973
OS: Debian 9.4
Tuner: WinTV-HVR-22XX
Apr 18, 2018 15:56:39.466 [0x7fc1727ff700] DEBUG - Auth: authenticated user 1 as
Apr 18, 2018 15:56:39.466 [0x7fc141bfd700] DEBUG - Request: [:41876 (WAN)] POST /livetv/dvrs/4/channels/5.1/tune (9 live) TLS GZIP Signed-in Token ()
Apr 18, 2018 15:56:39.468 [0x7fc141bfd700] DEBUG - DVR:Subscription: Starting a new rolling subscription for session t54inyp7g11cpp8nxadzcxgp channel 5.1.
Apr 18, 2018 15:56:39.580 [0x7fc141bfd700] DEBUG - Activity: registered new activity 958fd4b8-d843-4de6-a777-8d779db3716d - Processing subscriptions
Apr 18, 2018 15:56:39.580 [0x7fc141bfd700] DEBUG - DVR:Subscription: Scheduling subscriptions.
Apr 18, 2018 15:56:39.583 [0x7fc141bfd700] DEBUG - DVR:Subscription: Initialized 1 schedulers.
Apr 18, 2018 15:56:39.583 [0x7fc141bfd700] DEBUG - DVR:Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Apr 18, 2018 15:56:39.583 [0x7fc141bfd700] DEBUG - DVR:Schedule: Attempting to schedule the item ‘Live TV - Session’.
Apr 18, 2018 15:56:39.583 [0x7fc141bfd700] DEBUG - DVR:Schedule: Trying airing at 2018-04-18 15:56:39 on channel 5.1.
Apr 18, 2018 15:56:39.583 [0x7fc141bfd700] DEBUG - DVR:Schedule: Device 0 tuner 0 scheduled recording between 2018-04-18 15:56:39 and 2018-04-18 16:01:39 on channel 5.1.
Apr 18, 2018 15:56:39.583 [0x7fc141bfd700] DEBUG - DVR:Grabber: Starting grab immediately for ‘Live TV - Session’ and we’re 0 seconds in already.
Apr 18, 2018 15:56:39.583 [0x7fc141bfd700] DEBUG - DVR:Subscription: Scheduled all subscriptions in 2 ms.
Apr 18, 2018 15:56:39.583 [0x7fc141bfd700] DEBUG - Activity: Ended activity 958fd4b8-d843-4de6-a777-8d779db3716d.
Apr 18, 2018 15:56:39.583 [0x7fc141bfd700] DEBUG - DVR:Subscription: There are 1 grabs at the end.
Apr 18, 2018 15:56:39.583 [0x7fc141bfd700] DEBUG - DVR:Subscription: Waiting for media grab to start.
Apr 18, 2018 15:56:39.585 [0x7fc169ffe700] DEBUG - DVR:Grabber: Starting operation for ‘Live TV - Session’.
Apr 18, 2018 15:56:39.586 [0x7fc169ffe700] DEBUG - Activity: registered new activity d39ba173-9b87-42cf-bcce-dd8dfd9c758f - Recording
Apr 18, 2018 15:56:39.586 [0x7fc169ffe700] DEBUG - DVR:Grabber: Remote starting a media grab on device device://tv.plex.grabbers.tunerservice/dvb%23v4l%23%2fsys%2fdevices%2fpci0000%3a00%2f0000%3a00%3a05.0%2f0000%3a04%3a00.0%3a1131%3a7164 (Hauppauge WinTV-HVR-22XX, supported) tuner 0.
Apr 18, 2018 15:56:39.586 [0x7fc169ffe700] DEBUG - DVR:Grabber: Creating custom segmented recorder for grab for channel 5.1
Apr 18, 2018 15:56:39.589 [0x7fc169ffe700] DEBUG - DVR:Segmenter: Creating a new recorder for http://127.0.0.1:32600/devices/compound_device067dvb%23v4l%23%2Fsys%2Fdevices%2Fpci0000%3A00%2F0000%3A00%3A05.0%2F0000%3A04%3A00.0%3A1131%3A7164/media/triplet%3A%2F%2F0%3A1001%3A5?param=<%3Fxml%20version%3D"1.0"%20encoding%3D"UTF-8"%3F>
<tunedata><tt>8<%2Ftt><dq>0<%2Fdq><dr%2F><fr>29<%2Ffr><md>0<%2Fmd><sr>0<%2Fsr><pl>0<%2Fpl><lf>0<%2Flf><l1>0<%2Fl1><l2>0<%2Fl2><ls>0<%2Fls><pp>0<%2Fpp><hz>0<%2Fhz><in>0<%2Fin><fc>0<%2Ffc><t>1001<%2Ft><n>0<%2Fn><s>5<%2Fs><en>0<%2Fen><%2Ftunedata>
.
Apr 18, 2018 15:56:39.591 [0x7fc169ffe700] DEBUG - DVR:Grabber: Setting timer to stop grab of ‘Live TV - Session’ in 300 seconds.
Apr 18, 2018 15:56:39.591 [0x7fc169ffe700] DEBUG - DVR:Grabber: Waiting for a tuner (2 available).
Apr 18, 2018 15:56:39.591 [0x7fc169ffe700] DEBUG - DVR:Grabber: Allocated a tuner (1 left)
Apr 18, 2018 15:56:39.591 [0x7fc169ffe700] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Apr 18, 2018 15:56:39.591 [0x7fc169ffe700] DEBUG - Selecting best audio stream for part ID -1 (autoselect: 1 language: en)
Apr 18, 2018 15:56:39.592 [0x7fc169ffe700] DEBUG - We’re going to try to auto-select a subtitle.
Apr 18, 2018 15:56:39.592 [0x7fc169ffe700] DEBUG - Audio Stream: -1, Subtitle Stream: -1
Apr 18, 2018 15:56:39.592 [0x7fc169ffe700] DEBUG - MDE: Selected protocol hls; container: mpegts
Apr 18, 2018 15:56:39.592 [0x7fc169ffe700] ERROR - Unable to find title for item of type 5
Apr 18, 2018 15:56:39.592 [0x7fc169ffe700] DEBUG - MDE: analyzing media item -1
Apr 18, 2018 15:56:39.592 [0x7fc169ffe700] DEBUG - MDE: : no direct play video profile exists for http/mpegts/
Apr 18, 2018 15:56:39.592 [0x7fc169ffe700] DEBUG - MDE: : no direct play video profile exists for http/mpegts//
Apr 18, 2018 15:56:39.592 [0x7fc169ffe700] DEBUG - MDE: : codec is unavailable for analysis
Apr 18, 2018 15:56:39.592 [0x7fc169ffe700] DEBUG - MDE: : codec is unavailable for analysis
Apr 18, 2018 15:56:39.592 [0x7fc169ffe700] ERROR - Unable to find title for item of type 5
Apr 18, 2018 15:56:39.592 [0x7fc169ffe700] DEBUG - MDE: : selected media 0 / -1
Apr 18, 2018 15:56:39.593 [0x7fc169ffe700] DEBUG - Cleaning directory for session 323dbb53-ec27-49da-b99a-a7e7a859644b ()
Apr 18, 2018 15:56:39.593 [0x7fc169ffe700] DEBUG - Starting a transcode session 323dbb53-ec27-49da-b99a-a7e7a859644b at offset -1.0 (state=3)
Apr 18, 2018 15:56:39.593 [0x7fc169ffe700] DEBUG - Streaming Resource: Added session 0x7fc16f4580e0:323dbb53-ec27-49da-b99a-a7e7a859644b
Apr 18, 2018 15:56:39.596 [0x7fc169ffe700] VERBOSE - Sufficient disk space: 0B source file, 256.97GB capacity, 213.92GB available on “/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions”
Apr 18, 2018 15:56:39.596 [0x7fc169ffe700] DEBUG - Job running: EAE_ROOT=’/tmp/pms-468b8037-0142-4595-b67f-e4140175fa62/EasyAudioEncoder’ FFMPEG_EXTERNAL_LIBS=’/var/lib/plexmediaserver/Library/Application\ Support/Plex\ Media\ Server/Codecs/7814ac9-1285-linux-ubuntu-x86_64/’ 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’ ‘-i’ 'http://127.0.0.1:32600/devices/compound_device067dvb%23v4l%23%2Fsys%2Fdevices%2Fpci0000%3A00%2F0000%3A00%3A05.0%2F0000%3A04%3A00.0%3A1131%3A7164/media/triplet%3A%2F%2F0%3A1001%3A5?param=<%3Fxml%20version%3D"1.0"%20encoding%3D"UTF-8"%3F>
<tunedata><tt>8<%2Ftt><dq>0<%2Fdq><dr%2F><fr>29<%2Ffr><md>0<%2Fmd><sr>0<%2Fsr><pl>0<%2Fpl><lf>0<%2Flf><l1>0<%2Fl1><l2>0<%2Fl2><ls>0<%2Fls><pp>0<%2Fpp><hz>0<%2Fhz><in>0<%2Fin><fc>0<%2Ffc><t>1001<%2Ft><n>0<%2Fn><s>5<%2Fs><en>0<%2Fen><%2Ftunedata>
’ ‘-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.0625’ ‘-segment_list’ ‘http://127.0.0.1:32400/video/:/transcode/session/323dbb53-ec27-49da-b99a-a7e7a859644b/37ded15c-29d1-4c61-a135-8acc56b136c5/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’ ‘-progressurl’ ‘http://127.0.0.1:32400/video/:/transcode/session/323dbb53-ec27-49da-b99a-a7e7a859644b/37ded15c-29d1-4c61-a135-8acc56b136c5/progress’
Apr 18, 2018 15:56:39.599 [0x7fc169ffe700] DEBUG - Jobs: Starting child process with pid 1828
Apr 18, 2018 15:56:42.587 [0x7fc15bbfd700] DEBUG - Activity: updated activity d39ba173-9b87-42cf-bcce-dd8dfd9c758f - completed 1% - Recording
Apr 18, 2018 15:56:45.587 [0x7fc155bff700] DEBUG - Activity: updated activity d39ba173-9b87-42cf-bcce-dd8dfd9c758f - completed 2% - Recording
Apr 18, 2018 15:56:48.588 [0x7fc15bbfd700] DEBUG - Activity: updated activity d39ba173-9b87-42cf-bcce-dd8dfd9c758f - completed 3% - Recording
Apr 18, 2018 15:56:51.588 [0x7fc16efff700] DEBUG - Activity: updated activity d39ba173-9b87-42cf-bcce-dd8dfd9c758f - completed 4% - Recording
Apr 18, 2018 15:56:54.589 [0x7fc155bff700] DEBUG - Activity: updated activity d39ba173-9b87-42cf-bcce-dd8dfd9c758f - completed 5% - Recording
Apr 18, 2018 15:56:57.590 [0x7fc15bbfd700] DEBUG - Activity: updated activity d39ba173-9b87-42cf-bcce-dd8dfd9c758f - completed 6% - Recording
Apr 18, 2018 15:57:00.590 [0x7fc16efff700] DEBUG - Activity: updated activity d39ba173-9b87-42cf-bcce-dd8dfd9c758f - completed 7% - Recording
Apr 18, 2018 15:57:03.591 [0x7fc155bff700] DEBUG - Activity: updated activity d39ba173-9b87-42cf-bcce-dd8dfd9c758f - completed 8% - Recording
Apr 18, 2018 15:57:06.591 [0x7fc15bbfd700] DEBUG - Activity: updated activity d39ba173-9b87-42cf-bcce-dd8dfd9c758f - completed 9% - Recording
Apr 18, 2018 15:57:09.592 [0x7fc16efff700] DEBUG - Activity: updated activity d39ba173-9b87-42cf-bcce-dd8dfd9c758f - completed 10% - Recording
Apr 18, 2018 15:57:09.599 [0x7fc169ffe700] DEBUG - Using default transcode duration of 120 minutes
Apr 18, 2018 15:57:09.599 [0x7fc169ffe700] DEBUG - Started session successfully: 323dbb53-ec27-49da-b99a-a7e7a859644b
Apr 18, 2018 15:57:09.600 [0x7fc169ffe700] DEBUG - DVR:Recorder: It took 30.0 sec to start the session for http://127.0.0.1:32600/devices/compound_device067dvb%23v4l%23%2Fsys%2Fdevices%2Fpci0000%3A00%2F0000%3A00%3A05.0%2F0000%3A04%3A00.0%3A1131%3A7164/media/triplet%3A%2F%2F0%3A1001%3A5?param=<%3Fxml%20version%3D"1.0"%20encoding%3D"UTF-8"%3F>
<tunedata><tt>8<%2Ftt><dq>0<%2Fdq><dr%2F><fr>29<%2Ffr><md>0<%2Fmd><sr>0<%2Fsr><pl>0<%2Fpl><lf>0<%2Flf><l1>0<%2Fl1><l2>0<%2Fl2><ls>0<%2Fls><pp>0<%2Fpp><hz>0<%2Fhz><in>0<%2Fin><fc>0<%2Ffc><t>1001<%2Ft><n>0<%2Fn><s>5<%2Fs><en>0<%2Fen><%2Ftunedata>
Apr 18, 2018 15:57:09.600 [0x7fc169ffe700] DEBUG - DVR:Grabber: Merging EPG item with live item.
Apr 18, 2018 15:57:09.600 [0x7fc169ffe700] ERROR - DVR:Recorder: No part because session isn’t reporting one.
Apr 18, 2018 15:57:09.600 [0x7fc169ffe700] DEBUG - DVR:Grabber: Operation for Live TV - Session completed with status error (Could not start recording)
Apr 18, 2018 15:57:09.600 [0x7fc169ffe700] DEBUG - Activity: Ended activity d39ba173-9b87-42cf-bcce-dd8dfd9c758f.
Apr 18, 2018 15:57:09.601 [0x7fc169ffe700] DEBUG - DVR:Grabber: Cleaning up rolling grab for channel 5.1.
Apr 18, 2018 15:57:09.601 [0x7fc169ffe700] DEBUG - DVR:Recorder: Asked to stop recording ‘Live TV - Session’.
Apr 18, 2018 15:57:09.601 [0x7fc169ffe700] DEBUG - DVR:Recorder: Stopping transcode session.
Apr 18, 2018 15:57:09.601 [0x7fc169ffe700] DEBUG - Killing job.
Apr 18, 2018 15:57:09.601 [0x7fc169ffe700] DEBUG - Signalling job ID 1828 with 9
Apr 18, 2018 15:57:09.601 [0x7fc169ffe700] DEBUG - Job was already killed, not killing again.
Apr 18, 2018 15:57:09.601 [0x7fc169ffe700] DEBUG - Stopping transcode session 323dbb53-ec27-49da-b99a-a7e7a859644b
Apr 18, 2018 15:57:09.601 [0x7fc15bbfd700] DEBUG - Cleaning directory for session 323dbb53-ec27-49da-b99a-a7e7a859644b (/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-323dbb53-ec27-49da-b99a-a7e7a859644b)
Apr 18, 2018 15:57:09.603 [0x7fc165fff700] DEBUG - Jobs: ‘/usr/lib/plexmediaserver/Plex Transcoder’ exit code for process 1828 is -9 (signal: Killed)
Apr 18, 2018 15:57:09.603 [0x7fc169ffe700] DEBUG - DVR:Grabber: Freed a tuner (now 2 available)
Apr 18, 2018 15:57:09.603 [0x7fc141bfd700] ERROR - DVR:Subscription: Rolling media grab failed to start.
Apr 18, 2018 15:57:09.603 [0x7fc141bfd700] DEBUG - DVR:Grabber: Cancelling operation for ‘Live TV - Session’ with status cancelled.
Apr 18, 2018 15:57:09.603 [0x7fc141bfd700] DEBUG - DVR:Grabber: Operation was active, stopping recorder.
Apr 18, 2018 15:57:09.603 [0x7fc141bfd700] DEBUG - DVR:Grabber: We’ve cancelled the grab with 270 seconds remaining.
Apr 18, 2018 15:57:09.603 [0x7fc141bfd700] DEBUG - DVR:Grabber: Cleaning up rolling grab for channel 5.1.
Apr 18, 2018 15:57:09.603 [0x7fc141bfd700] DEBUG - DVR:Recorder: Asked to stop recording ‘Live TV - Session’.
Apr 18, 2018 15:57:09.603 [0x7fc154bff700] DEBUG - DVR:Grabber: Recording for ‘Live TV - Session’ stopped with status complete.
Apr 18, 2018 15:57:09.603 [0x7fc154bff700] DEBUG - DVR:Grabber: This was a rolling sub, not assimilating.
Apr 18, 2018 15:57:09.636 [0x7fc141bfd700] DEBUG - Activity: registered new activity 6f3ff15b-8de9-4135-a585-791929ea7600 - Processing subscriptions
Apr 18, 2018 15:57:09.636 [0x7fc141bfd700] DEBUG - DVR:Subscription: Scheduling subscriptions.
Apr 18, 2018 15:57:09.639 [0x7fc141bfd700] DEBUG - DVR:Subscription: Initialized 1 schedulers.
Apr 18, 2018 15:57:09.639 [0x7fc141bfd700] DEBUG - DVR:Subscription: Starting with 1 grabs, scheduled 0 active ones preemptively.
Apr 18, 2018 15:57:09.639 [0x7fc141bfd700] DEBUG - DVR:Subscription: Scheduled all subscriptions in 2 ms.
Apr 18, 2018 15:57:09.639 [0x7fc141bfd700] DEBUG - Activity: Ended activity 6f3ff15b-8de9-4135-a585-791929ea7600.
Apr 18, 2018 15:57:09.639 [0x7fc141bfd700] DEBUG - DVR:Subscription: There are 0 grabs at the end.
Apr 18, 2018 15:57:09.641 [0x7fc171ffe700] DEBUG - Completed: [:41876] 200 POST /livetv/dvrs/4/channels/5.1/tune (5 live) TLS GZIP 30174ms 512 bytes (pipelined: 5)
Apr 18, 2018 15:57:09.645 [0x7fc154bff700] DEBUG - Activity: registered new activity db1588a9-cb3f-45b0-a3e3-9d918ba8ee39 - Processing subscriptions
Apr 18, 2018 15:57:09.645 [0x7fc154bff700] DEBUG - DVR:Subscription: Scheduling subscriptions.
Apr 18, 2018 15:57:09.648 [0x7fc154bff700] DEBUG - DVR:Subscription: Initialized 1 schedulers.
Apr 18, 2018 15:57:09.648 [0x7fc154bff700] DEBUG - DVR:Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Apr 18, 2018 15:57:09.648 [0x7fc154bff700] DEBUG - DVR:Subscription: Scheduled all subscriptions in 2 ms.
Apr 18, 2018 15:57:09.648 [0x7fc154bff700] DEBUG - Activity: Ended activity db1588a9-cb3f-45b0-a3e3-9d918ba8ee39.
Apr 18, 2018 15:57:09.648 [0x7fc154bff700] DEBUG - DVR:Subscription: There are 0 grabs at the end.
Apr 18, 2018 15:57:09.659 [0x7fc1727ff700] DEBUG - Auth: authenticated user 1 as
Apr 18, 2018 15:57:09.660 [0x7fc169ffe700] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Apr 18, 2018 15:57:09.660 [0x7fc169ffe700] DEBUG - HTTP requesting GET https://plex.tv/servers//access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Apr 18, 2018 15:57:09.660 [0x7fc1737ff700] DEBUG - Request: [:41900 (WAN)] GET /status/sessions/background (5 live) TLS GZIP Signed-in Token ()
Apr 18, 2018 15:57:09.663 [0x7fc1727ff700] DEBUG - Completed: [:41900] 200 GET /status/sessions/background (4 live) TLS GZIP 3ms 470 bytes (pipelined: 1)
Apr 18, 2018 15:57:10.266 [0x7fc169ffe700] DEBUG - HTTP 200 response from GET https://plex.tv/servers/4c570373a3adc57c6904da670018439ac351147a/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Apr 18, 2018 15:57:10.269 [0x7fc169ffe700] DEBUG - MyPlex: updating with 64 access tokens
Apr 18, 2018 15:57:10.591 [0x7fc16bfff700] DEBUG - DVR:Recorder: Asked to stop recording ‘Live TV - Session’.
