Recording failed. Please check your tuner or antenna

[cantak]

Server Version#: 4.69.1
Player Version#: 4.69.1
Tuner Make/Model: Hauppauge WinTV quadHD PCI Express
Guide/Lineup name: Don’t see a name?
Using XMLTV?: Never heard of this?
Channel number/Name: 11.1

No clue what guide name means but no issues there. Problem is I can’t get some shows to record. I can tune into 11.1 just fine. I can record other shows. But I’ve missed two shows in a row from recording off this channel.

Mar 03, 2022 18:57:02.096 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Subscription: No existing library item show ‘guid: plex://show/5fc6af311a65df002dd5dad3 ratingKey: plex%3A%2F%2Fshow%2F5fc6af311a65df002dd5dad3 thumb: https://metadata-static.plex.tv/4/gracenote/4a8d1dfb3c42c2bc5c17cf70c1976ed8.jpg title: Law & Order type: 2 year: 1990’
Mar 03, 2022 18:57:02.096 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Starting operation for ‘Law & Order - E2 - Impossible Dream’ on channel 5fc76e5a992cb9002eaf825e-5fc705f7088e7d002d4c718e.
Mar 03, 2022 18:57:02.096 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Activity: registered new activity c1677b29-ed29-48df-acc4-20e398dc9fa1 - “Recording”
Mar 03, 2022 18:57:02.096 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Grabber: Remote starting a media grab on device device://tv.plex.grabbers.tunerservice/dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23 (Hauppauge WinTV-quadHD, supported) tuner 0.
Mar 03, 2022 18:57:02.096 [17532] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] HTTP requesting GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23
Mar 03, 2022 18:57:02.098 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Subscription: No existing library item show ‘guid: plex://show/5fc6af311a65df002dd5dad3 ratingKey: plex%3A%2F%2Fshow%2F5fc6af311a65df002dd5dad3 thumb: https://metadata-static.plex.tv/4/gracenote/4a8d1dfb3c42c2bc5c17cf70c1976ed8.jpg title: Law & Order type: 2 year: 1990’
Mar 03, 2022 18:57:02.098 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Grabber: checking for 9006 MB of available disk space in grab path and 100 MB in the transcode cache path
Mar 03, 2022 18:57:02.101 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Grabber: Saving recording to “E:\Recorded TV Shows.grab\4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1-1f8d198c44e1bfb0ad72bc565f1f01c8bccc240b”
Mar 03, 2022 18:57:02.101 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Grabber: Going to record for 3958 seconds, final URL is http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23/media/triplet%3A%2F%2F0%3A1589%3A3?param=<%3Fxml%20version%3D"1.0"%20encoding%3D"UTF-8"%3F> <tunedata><tt>8<%2Ftt><dq>0<%2Fdq><dr%2F><fr>11<%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>1589<%2Ft><n>0<%2Fn><s>3<%2Fs><en>0<%2Fen><%2Ftunedata>
Mar 03, 2022 18:57:02.101 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] MediaRecorderVirtual: setting stop time to 2022-03-03 20:03:00
Mar 03, 2022 18:57:02.101 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] DVR:Segmenter: Found existing stopped recorder ‘91fcca70-7dfe-4e97-82d8-aac3257cf777’ for 'http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23/media/triplet%3A%2F%2F0%3A1589%3A3?param=<%3Fxml%20version%3D"1.0"%20encoding%3D"UTF-8"%3F> <tunedata><tt>8<%2Ftt><dq>0<%2Fdq><dr%2F><fr>11<%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>1589<%2Ft><n>0<%2Fn><s>3<%2Fs><en>0<%2Fen><%2Ftunedata> '; replacing.
Mar 03, 2022 18:57:02.101 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] DVR:Segmenter: Creating a new recorder for http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23/media/triplet%3A%2F%2F0%3A1589%3A3?param=<%3Fxml%20version%3D"1.0"%20encoding%3D"UTF-8"%3F> <tunedata><tt>8<%2Ftt><dq>0<%2Fdq><dr%2F><fr>11<%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>1589<%2Ft><n>0<%2Fn><s>3<%2Fs><en>0<%2Fen><%2Ftunedata> .
Mar 03, 2022 18:57:02.102 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Grabber: Waiting for a tuner on device://tv.plex.grabbers.tunerservice/dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23 (2 available) for at most 3958 seconds.
Mar 03, 2022 18:57:02.102 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Grabber: Allocated a tuner on device://tv.plex.grabbers.tunerservice/dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23 (1 left)
Mar 03, 2022 18:57:02.103 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] We’re going to try to auto-select an audio stream for account 1.
Mar 03, 2022 18:57:02.103 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Selecting best audio stream for part ID -1 (autoselect: 1 language: en)
Mar 03, 2022 18:57:02.103 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] We’re going to try to auto-select a subtitle.
Mar 03, 2022 18:57:02.103 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Audio Stream: -1, Subtitle Stream: -1
Mar 03, 2022 18:57:02.103 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] MDE: Selected protocol hls; container: mpegts
Mar 03, 2022 18:57:02.103 [11856] ERROR - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Unable to find title for item of type 5
Mar 03, 2022 18:57:02.103 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] MDE: analyzing media item -1
Mar 03, 2022 18:57:02.103 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] MDE: : no direct play video profile exists for http/mpegts/
Mar 03, 2022 18:57:02.103 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] MDE: : no direct play video profile exists for http/mpegts//
Mar 03, 2022 18:57:02.103 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] MDE: : codec is unavailable for analysis
Mar 03, 2022 18:57:02.103 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] MDE: : codec is unavailable for analysis
Mar 03, 2022 18:57:02.103 [11856] ERROR - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Unable to find title for item of type 5
Mar 03, 2022 18:57:02.103 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] MDE: : selected media 0 / -1
Mar 03, 2022 18:57:02.103 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Cleaning directory for session b3dc7c3c-d7ff-48d4-a5bf-85f62517476f ()
Mar 03, 2022 18:57:02.103 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Starting a transcode session b3dc7c3c-d7ff-48d4-a5bf-85f62517476f at offset -1.0 (state=3)
Mar 03, 2022 18:57:02.103 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Streaming Resource: Added session 0DE009A8:b3dc7c3c-d7ff-48d4-a5bf-85f62517476f
Mar 03, 2022 18:57:02.104 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1/JobRunner] Job running: set “FFMPEG_EXTERNAL_LIBS=\\?\C:\Users\Paul\AppData\Local\Plex\ Media\ Server\Codecs\62cc2bc-4226-windows-x86\” & set “X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxx” & “C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe” -noaccurate_seek -ignore_unknown -scan_all_pmts -1 -rw_timeout 30000000 -reconnect 1 -reconnect_streamed 1 -reconnect_delay_max 30 -fflags +discardcorruptts+fillwallclockdts -probesize 20000000 -i http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23/media/triplet%3A%2F%2F0%3A1589%3A3?param=<%3Fxml%20version%3D"1.0"%20encoding%3D"UTF-8"%3F> <tunedata><tt>8<%2Ftt><dq>0<%2Fdq><dr%2F><fr>11<%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>1589<%2Ft><n>0<%2Fn><s>3<%2Fs><en>0<%2Fen><%2Ftunedata> -map 0:V? -codec:V copy -map 0:a? -codec:a copy -map 0:s? -codec:s copy -break_non_keyframes 1 -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/b3dc7c3c-d7ff-48d4-a5bf-85f62517476f/fd139bbe-f675-4f36-9c8c-e756acf0ad25/manifest?X-Plex-Http-Pipeline=infinite -segment_list_type csv -segment_list_size 5 -segment_list_separate_stream_times 1 -segment_list_unfinished 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/b3dc7c3c-d7ff-48d4-a5bf-85f62517476f/fd139bbe-f675-4f36-9c8c-e756acf0ad25/progress
Mar 03, 2022 18:57:02.108 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1/JobRunner] Jobs: Starting child process with pid 9776
Mar 03, 2022 18:57:02.114 [12552] DEBUG - [HttpClient] HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23
Mar 03, 2022 18:57:02.215 [13564] DEBUG - Request: [127.0.0.1:54785 (Loopback)] PUT /video/:/transcode/session/b3dc7c3c-d7ff-48d4-a5bf-85f62517476f/fd139bbe-f675-4f36-9c8c-e756acf0ad25/progress?status=startup (5 live) Signed-in Token (cantak) (range: bytes=0-)
Mar 03, 2022 18:57:02.215 [4784] DEBUG - Completed: [127.0.0.1:54785] 204 PUT /video/:/transcode/session/b3dc7c3c-d7ff-48d4-a5bf-85f62517476f/fd139bbe-f675-4f36-9c8c-e756acf0ad25/progress?status=startup (5 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-)
Mar 03, 2022 18:57:02.281 [13564] DEBUG - Request: [127.0.0.1:54785 (Loopback)] PUT /video/:/transcode/session/b3dc7c3c-d7ff-48d4-a5bf-85f62517476f/fd139bbe-f675-4f36-9c8c-e756acf0ad25/progress?status=startup (5 live) Signed-in Token (cantak) (range: bytes=0-)
Mar 03, 2022 18:57:02.281 [4784] DEBUG - Completed: [127.0.0.1:54785] 204 PUT /video/:/transcode/session/b3dc7c3c-d7ff-48d4-a5bf-85f62517476f/fd139bbe-f675-4f36-9c8c-e756acf0ad25/progress?status=startup (5 live) 0ms 203 bytes (pipelined: 2) (range: bytes=0-)
Mar 03, 2022 18:57:02.281 [13564] DEBUG - Request: [127.0.0.1:54785 (Loopback)] PUT /video/:/transcode/session/b3dc7c3c-d7ff-48d4-a5bf-85f62517476f/fd139bbe-f675-4f36-9c8c-e756acf0ad25/progress?status=opening (5 live) Signed-in Token (cantak) (range: bytes=0-)
Mar 03, 2022 18:57:02.281 [4784] DEBUG - Completed: [127.0.0.1:54785] 204 PUT /video/:/transcode/session/b3dc7c3c-d7ff-48d4-a5bf-85f62517476f/fd139bbe-f675-4f36-9c8c-e756acf0ad25/progress?status=opening (5 live) 0ms 203 bytes (pipelined: 3) (range: bytes=0-)
Mar 03, 2022 18:57:05.130 [10596] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] HTTP requesting GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23
Mar 03, 2022 18:57:12.448 [12552] DEBUG - [HttpClient] HTTP/1.1 (7.3s) 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23 (reused)
Mar 03, 2022 18:57:15.454 [17532] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] HTTP requesting GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23
Mar 03, 2022 18:57:19.677 [12552] DEBUG - [HttpClient] HTTP/1.1 (4.2s) 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23 (reused)
Mar 03, 2022 18:57:22.680 [17532] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] HTTP requesting GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23
Mar 03, 2022 18:57:25.624 [12552] DEBUG - [HttpClient] HTTP/1.1 (2.9s) 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23 (reused)
Mar 03, 2022 18:57:27.113 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] TranscodeSession: timed out waiting to find duration for live session
Mar 03, 2022 18:57:27.113 [11856] ERROR - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Failed to start session.
Mar 03, 2022 18:57:27.113 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Streaming Resource: Terminated session 0DE009A8:b3dc7c3c-d7ff-48d4-a5bf-85f62517476f with reason Recording failed. Please check your tuner or antenna.
Mar 03, 2022 18:57:27.113 [11856] ERROR - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Recorder: Unable to create transcode session or session failed to start.
Mar 03, 2022 18:57:27.113 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Killing job.
Mar 03, 2022 18:57:27.115 [11856] ERROR - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Recorder: Error 16 (Recording failed. Please check your tuner or antenna.) starting the record, shutting things down.
Mar 03, 2022 18:57:27.115 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] MediaRecorderVirtual: setting stop time to 2022-03-03 18:57:27
Mar 03, 2022 18:57:27.115 [10596] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Job was already killed, not killing again.
Mar 03, 2022 18:57:27.115 [10596] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Job was already killed, not killing again.
Mar 03, 2022 18:57:27.115 [10596] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Stopping transcode session b3dc7c3c-d7ff-48d4-a5bf-85f62517476f
Mar 03, 2022 18:57:27.115 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Recorder: Asked to stop recording ‘Law & Order - E2 - Impossible Dream’ on channel 5fc76e5a992cb9002eaf825e-5fc705f7088e7d002d4c718e.
Mar 03, 2022 18:57:27.115 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Grabber: Freed a tuner on device://tv.plex.grabbers.tunerservice/dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23 (now 2 available)
Mar 03, 2022 18:57:27.115 [11856] ERROR - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Recorder: Error 16 (Recording failed. Please check your tuner or antenna.) starting the record, shutting things down.
Mar 03, 2022 18:57:27.115 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] MediaRecorderVirtual: setting stop time to 2022-03-03 18:57:27
Mar 03, 2022 18:57:27.115 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Grabber: Operation for ‘Law & Order - E2 - Impossible Dream’ on channel 5fc76e5a992cb9002eaf825e-5fc705f7088e7d002d4c718e completed with status error (Recording failed. Please check your tuner or antenna.)
Mar 03, 2022 18:57:27.115 [1072] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Cleaning directory for session b3dc7c3c-d7ff-48d4-a5bf-85f62517476f (C:\Users\Paul\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-b3dc7c3c-d7ff-48d4-a5bf-85f62517476f)
Mar 03, 2022 18:57:27.115 [20380] DEBUG - Jobs: ‘C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe’ exit code for process 9776 is -1059143458 (intentional termination)
Mar 03, 2022 18:57:27.116 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Activity: Ended activity c1677b29-ed29-48df-acc4-20e398dc9fa1.
Mar 03, 2022 18:57:27.116 [11856] ERROR - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Starting media grab failed.
Mar 03, 2022 18:57:27.116 [20144] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Activity: updated activity 9d765f2e-4bc0-4fe7-8c7e-eade7670f035 - completed 100.0% - Refreshing Sub
Mar 03, 2022 18:57:27.116 [17532] DEBUG - HTTP requesting GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23

[hokierulz]

It’s a bit limiting to try to analyze from a log snippet, but I think you have the same intermittent recording problem that I’ve experienced a couple times. The significant error is:

Mar 03, 2022 18:57:27.113 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] TranscodeSession: timed out waiting to find duration for live session
Mar 03, 2022 18:57:27.113 [11856] ERROR - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Failed to start session.

FYI, it is normal for Plex to start a transcode session as part of a recording.

I recommend deleting your codecs so that Plex can redownload them as needed - apparently over time they can become corrupted. When I’ve had intermittent recording issues this has fixed the problem for me.

The codec folder is a subdirectory of the Plex data directory. (I’m running on a Linux server, not Win, so yours will be in a different location than mine.) I typically stop the Plex Server service, delete the files in the codec subfolder with the naming pattern of uniqueidentifier-OS-processorclass (I don’t delete the folder itself - feel free to backup the files first for safety), and then start Plex Server. Plex will re-download the codecs as needed. I also posted about this (partly for my own reference) when this first happened to me.

[cantak]

Plex redownloaded 3 of the 6 files that were there. I assume performing different operations like watching a different encoded movie would continue to download as needed. But I did tune into live TV on the channel in question so I am sure the three that downloaded are the ones needed. A file comparison tool shows no differences between these three and the older copies. So I don’t think this will solve it.

[sixxnet]

I agree with @hokierulz here. Clearing out the Codecs folder seems to get rid of issues related to Live TV quite often.

[hokierulz]

It only downloads the codecs on demand as needed. As you watch other channels and/or videos it will download codecs based on the audio/video formats.

I suggest giving the recording a try to see if it works since you have already gone through the delete process. I’ve had it fix my recording issue when there was no apparent difference in the codec files.

[cantak]

No luck. The next episode failed as well. This time it was 19 minutes in when it failed.

[hokierulz]

Would you be able to post full debug level logs that include the timeframe of the failure? There are other log files other than just the media server logs that might provide insight.

[cantak]

Plex Media Server Logs_2022-03-13_20-05-16.zip (3.6 MB)

Sure thing. I’ll just post the current set of logs. I appreciate you looking. The last failure was March 10th at 7:19pm. It recorded 19 minutes of a 1 hour show that started at 7:00pm.

[hokierulz]

Thank you for the logs! Sometimes with full logs the problem will jump out, but not this time. Also, there were two recordings that started on March 10th at 7:00pm. It makes for more to look at in a short timeframe, but was useful to see a successful recording next to the failure.

I think what is happening is that your tuner is significantly slower responding when trying to tune the problematic channel, and combined with processes starting the 20 second timeout limit for the transcoder to start is being reached. Log examples are below. The Plex Tuner Service listens on port 32600, and in turn communicates with your tuner. I don’t see any actual errors in the Plex Tuner Service logs, just slow response from the tuner, but I don’t have a Hauppage tuner and am not as familiar with the details.

I thought I had a good way for you to proceed. Plex used to let you set the length of this timeout in the Web GUI. You can see it in this article under “Advanced Settings”. Unfortunately that setting appears to have been removed. Perhaps it is still available in a settings file somewhere - I’ll post if I can find it.

The other alternative is to figure out why your tuner is slow tuning on that channel. I’d start by looking at if you have low signal strength or quality on that channel.

I can’t fully tell you why you can watch that channel in Live TV. Perhaps sometimes your tuner responds just fast enough to start in under 20 seconds. Plex does seem to handle the transcoder somewhat differently with Live TV vs recording, but Plex is closed source so we can’t look at the code to tell fully.

I hope this helps. I’ll post back if I find that transcoder timeout setting.

Log examples

Successful record start with four calls to the tuner, each with a 0.0s response time

Mar 10, 2022 19:00:01.872 [24060] DEBUG - [Grabber/44c3f7db88c104b1a7a972b13a09e5366d086f24/JobRunner] Job running: set "FFMPEG_EXTERNAL_LIBS=\\\\?\\C\:\\Users\\Paul\\AppData\\Local\\Plex\ Media\ Server\\Codecs\\62cc2bc-4226-windows-x86\\" & set "X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxx" & "C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe" -noaccurate_seek -ignore_unknown -scan_all_pmts -1 -rw_timeout 30000000 -reconnect 1 -reconnect_streamed 1 -reconnect_delay_max 30 -fflags +discardcorruptts+fillwallclockdts -probesize 20000000 -i http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6b180070%26rev_04%236%2637178e2d%260%26001000e5%23/media/triplet%3A%2F%2F0%3A1585%3A1?param=%3C%3Fxml%20version%3D%221%2E0%22%20encoding%3D%22UTF-8%22%3F%3E%0A%3Ctunedata%3E%3Ctt%3E8%3C%2Ftt%3E%3Cdq%3E0%3C%2Fdq%3E%3Cdr%2F%3E%3Cfr%3E32%3C%2Ffr%3E%3Cmd%3E0%3C%2Fmd%3E%3Csr%3E0%3C%2Fsr%3E%3Cpl%3E0%3C%2Fpl%3E%3Clf%3E0%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%3E1585%3C%2Ft%3E%3Cn%3E0%3C%2Fn%3E%3Cs%3E1%3C%2Fs%3E%3Cen%3E0%3C%2Fen%3E%3C%2Ftunedata%3E%0A -map 0:V? -codec:V copy -map 0:a? -codec:a copy -map 0:s? -codec:s copy -break_non_keyframes 1 -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/22d312ee-4ed9-43a3-a8c6-7b4cd5bfa00a/ca8bf483-12d9-4e90-9e94-0139bb95aa59/manifest?X-Plex-Http-Pipeline=infinite -segment_list_type csv -segment_list_size 5 -segment_list_separate_stream_times 1 -segment_list_unfinished 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/22d312ee-4ed9-43a3-a8c6-7b4cd5bfa00a/ca8bf483-12d9-4e90-9e94-0139bb95aa59/progress
Mar 10, 2022 19:00:01.873 [20356] WARN - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] IsFileWritable: failed to remove temporary file '"C:\Users\Paul\AppData\Local\Plex Media Server\Cache\7a499e02-e665-4687-82e9-2421ae8a8b77"': boost::filesystem::remove: The process cannot access the file because it is being used by another process: "C:\Users\Paul\AppData\Local\Plex Media Server\Cache\7a499e02-e665-4687-82e9-2421ae8a8b77"
Mar 10, 2022 19:00:01.875 [11336] DEBUG - [HttpClient] HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6b180070%26rev_04%236%2637178e2d%260%26001000e5%23
Mar 10, 2022 19:00:01.875 [11464] DEBUG - [Grabber/44c3f7db88c104b1a7a972b13a09e5366d086f24] HTTP requesting GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23
Mar 10, 2022 19:00:01.878 [11336] DEBUG - [HttpClient] HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23 (reused)
Mar 10, 2022 19:00:01.878 [14916] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] HTTP requesting GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6b180070%26rev_04%236%2637178e2d%260%26001000e5%23
Mar 10, 2022 19:00:01.879 [24060] DEBUG - [Grabber/44c3f7db88c104b1a7a972b13a09e5366d086f24/JobRunner] Jobs: Starting child process with pid 18628
Mar 10, 2022 19:00:01.879 [20356] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5/JobRunner] Job running: set "FFMPEG_EXTERNAL_LIBS=\\\\?\\C\:\\Users\\Paul\\AppData\\Local\\Plex\ Media\ Server\\Codecs\\62cc2bc-4226-windows-x86\\" & set "X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxx" & "C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe" -noaccurate_seek -ignore_unknown -scan_all_pmts -1 -rw_timeout 30000000 -reconnect 1 -reconnect_streamed 1 -reconnect_delay_max 30 -fflags +discardcorruptts+fillwallclockdts -probesize 20000000 -i http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23/media/triplet%3A%2F%2F0%3A1589%3A3?param=%3C%3Fxml%20version%3D%221%2E0%22%20encoding%3D%22UTF-8%22%3F%3E%0A%3Ctunedata%3E%3Ctt%3E8%3C%2Ftt%3E%3Cdq%3E0%3C%2Fdq%3E%3Cdr%2F%3E%3Cfr%3E11%3C%2Ffr%3E%3Cmd%3E0%3C%2Fmd%3E%3Csr%3E0%3C%2Fsr%3E%3Cpl%3E0%3C%2Fpl%3E%3Clf%3E0%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%3E1589%3C%2Ft%3E%3Cn%3E0%3C%2Fn%3E%3Cs%3E3%3C%2Fs%3E%3Cen%3E0%3C%2Fen%3E%3C%2Ftunedata%3E%0A -map 0:V? -codec:V copy -map 0:a? -codec:a copy -map 0:s? -codec:s copy -break_non_keyframes 1 -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/f00ff93a-bc84-4693-8471-72c24f9f2340/3a7d4c47-4df6-4e28-8f7a-faa88fc76923/manifest?X-Plex-Http-Pipeline=infinite -segment_list_type csv -segment_list_size 5 -segment_list_separate_stream_times 1 -segment_list_unfinished 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/f00ff93a-bc84-4693-8471-72c24f9f2340/3a7d4c47-4df6-4e28-8f7a-faa88fc76923/progress
Mar 10, 2022 19:00:01.881 [11336] DEBUG - [HttpClient] HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6b180070%26rev_04%236%2637178e2d%260%26001000e5%23 (reused)
Mar 10, 2022 19:00:01.882 [14916] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] HTTP requesting GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23
Mar 10, 2022 19:00:01.885 [11336] DEBUG - [HttpClient] HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23 (reused)
Mar 10, 2022 19:00:01.885 [20356] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5/JobRunner] Jobs: Starting child process with pid 14452

Failed record from original log snippet, calls to the tuner with response times of 7.3s, 4.2s, and 2.9s

Mar 03, 2022 18:57:02.104 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1/JobRunner] Job running: set “FFMPEG_EXTERNAL_LIBS=\\?\C:\Users\Paul\AppData\Local\Plex\ Media\ Server\Codecs\62cc2bc-4226-windows-x86\” & set “X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxx” & “C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe” -noaccurate_seek -ignore_unknown -scan_all_pmts -1 -rw_timeout 30000000 -reconnect 1 -reconnect_streamed 1 -reconnect_delay_max 30 -fflags +discardcorruptts+fillwallclockdts -probesize 20000000 -i http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23/media/triplet%3A%2F%2F0%3A1589%3A3?param=<%3Fxml%20version%3D"1.0"%20encoding%3D"UTF-8"%3F> <tunedata><tt>8<%2Ftt><dq>0<%2Fdq><dr%2F><fr>11<%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>1589<%2Ft><n>0<%2Fn><s>3<%2Fs><en>0<%2Fen><%2Ftunedata> -map 0:V? -codec:V copy -map 0:a? -codec:a copy -map 0:s? -codec:s copy -break_non_keyframes 1 -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/b3dc7c3c-d7ff-48d4-a5bf-85f62517476f/fd139bbe-f675-4f36-9c8c-e756acf0ad25/manifest?X-Plex-Http-Pipeline=infinite -segment_list_type csv -segment_list_size 5 -segment_list_separate_stream_times 1 -segment_list_unfinished 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/b3dc7c3c-d7ff-48d4-a5bf-85f62517476f/fd139bbe-f675-4f36-9c8c-e756acf0ad25/progress
Mar 03, 2022 18:57:02.108 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1/JobRunner] Jobs: Starting child process with pid 9776
Mar 03, 2022 18:57:02.114 [12552] DEBUG - [HttpClient] HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23
Mar 03, 2022 18:57:02.215 [13564] DEBUG - Request: [127.0.0.1:54785 (Loopback)] PUT /video/:/transcode/session/b3dc7c3c-d7ff-48d4-a5bf-85f62517476f/fd139bbe-f675-4f36-9c8c-e756acf0ad25/progress?status=startup (5 live) Signed-in Token (cantak) (range: bytes=0-)
Mar 03, 2022 18:57:02.215 [4784] DEBUG - Completed: [127.0.0.1:54785] 204 PUT /video/:/transcode/session/b3dc7c3c-d7ff-48d4-a5bf-85f62517476f/fd139bbe-f675-4f36-9c8c-e756acf0ad25/progress?status=startup (5 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-)
Mar 03, 2022 18:57:02.281 [13564] DEBUG - Request: [127.0.0.1:54785 (Loopback)] PUT /video/:/transcode/session/b3dc7c3c-d7ff-48d4-a5bf-85f62517476f/fd139bbe-f675-4f36-9c8c-e756acf0ad25/progress?status=startup (5 live) Signed-in Token (cantak) (range: bytes=0-)
Mar 03, 2022 18:57:02.281 [4784] DEBUG - Completed: [127.0.0.1:54785] 204 PUT /video/:/transcode/session/b3dc7c3c-d7ff-48d4-a5bf-85f62517476f/fd139bbe-f675-4f36-9c8c-e756acf0ad25/progress?status=startup (5 live) 0ms 203 bytes (pipelined: 2) (range: bytes=0-)
Mar 03, 2022 18:57:02.281 [13564] DEBUG - Request: [127.0.0.1:54785 (Loopback)] PUT /video/:/transcode/session/b3dc7c3c-d7ff-48d4-a5bf-85f62517476f/fd139bbe-f675-4f36-9c8c-e756acf0ad25/progress?status=opening (5 live) Signed-in Token (cantak) (range: bytes=0-)
Mar 03, 2022 18:57:02.281 [4784] DEBUG - Completed: [127.0.0.1:54785] 204 PUT /video/:/transcode/session/b3dc7c3c-d7ff-48d4-a5bf-85f62517476f/fd139bbe-f675-4f36-9c8c-e756acf0ad25/progress?status=opening (5 live) 0ms 203 bytes (pipelined: 3) (range: bytes=0-)
Mar 03, 2022 18:57:05.130 [10596] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] HTTP requesting GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23
Mar 03, 2022 18:57:12.448 [12552] DEBUG - [HttpClient] HTTP/1.1 (7.3s) 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23 (reused)
Mar 03, 2022 18:57:15.454 [17532] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] HTTP requesting GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23
Mar 03, 2022 18:57:19.677 [12552] DEBUG - [HttpClient] HTTP/1.1 (4.2s) 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23 (reused)
Mar 03, 2022 18:57:22.680 [17532] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] HTTP requesting GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23
Mar 03, 2022 18:57:25.624 [12552] DEBUG - [HttpClient] HTTP/1.1 (2.9s) 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23 (reused)
Mar 03, 2022 18:57:27.113 [11856] DEBUG - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] TranscodeSession: timed out waiting to find duration for live session
Mar 03, 2022 18:57:27.113 [11856] ERROR - [Grabber/4bb93d6f5fff29e6b6f4fa36e13bb5426eca13b1] Failed to start session.

This is the case where another recording had already started on a different channel and was producing log entries during the same timeframe, so I extracted calls to the tuner for starting of failed recording. Response times of 7.2s, 4.2s, and 4.4s.

Mar 10, 2022 19:00:12.280 [11336] DEBUG - [HttpClient] HTTP/1.1 (7.4s) 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6b180070%26rev_04%236%2637178e2d%260%26001000e5%23 (reused)
Mar 10, 2022 19:00:16.515 [11336] DEBUG - [HttpClient] HTTP/1.1 (4.2s) 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23 (reused)
Mar 10, 2022 19:00:24.967 [11336] DEBUG - [HttpClient] HTTP/1.1 (4.4s) 200 response from GET http://127.0.0.1:32600/devices/compound_device075dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23 (reused)

[hokierulz]

Here is the list of hidden settings. Plex overhauled their transcoder, and it looks like they completely removed the setting for overriding the transcoder timeout.

Anyone please jump in if you know a way to override this transcoder timeout!

[cantak]

Thanks for reviewing them. The odd thing is that it recorded 20 minutes of the show and then failed. At least on the 10th. It should be able to handle 4 shows at once. As a test I started recording three separate channels on live tv, then added in this channel (11.1) as the 4th channel being recorded. My 4th recording indicated it was recording and then failed. Next, I started 11.1 recording first and then added in three other shows after the fact. All recorded fine. It seems that perhaps one of the tuners isn’t working properly with 11.1? And perhaps this tuner that isn’t working is not the first choice for Plex. So if I am recording multiple shows the good tuner gets filled up first on other channels, and then the bad tuner can’t pick up 11.1 when it starts. Any way to specify the priority?

[hokierulz]

Am I correct that the recording on the 10th that gave you 20 minutes was “Law & Order - E3 - Filtered Life”? If so, did you actually watch any of what was recorded? I’ll go one weirder from what I’m seeing in the logs. It looks like it tried to start the recording at 7:00pm, which is one of the cases where I saw the slow tuner responses cause the failure:

Mar 10, 2022 19:00:26.896 [20356] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] TranscodeSession: timed out waiting to find duration for live session
Mar 10, 2022 19:00:26.896 [20356] ERROR - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Failed to start session.
Mar 10, 2022 19:00:26.896 [20356] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Streaming Resource: Terminated session 0579D180:f00ff93a-bc84-4693-8471-72c24f9f2340 with reason Recording failed. Please check your tuner or antenna.
Mar 10, 2022 19:00:26.896 [20356] ERROR - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Recorder: Unable to create transcode session or session failed to start.
Mar 10, 2022 19:00:26.896 [20356] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Killing job.
Mar 10, 2022 19:00:26.903 [14484] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Job was already killed, not killing again.
Mar 10, 2022 19:00:26.903 [14484] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Job was already killed, not killing again.
Mar 10, 2022 19:00:26.903 [14484] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Stopping transcode session f00ff93a-bc84-4693-8471-72c24f9f2340
Mar 10, 2022 19:00:26.903 [14916] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Cleaning directory for session f00ff93a-bc84-4693-8471-72c24f9f2340 (C:\Users\Paul\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-f00ff93a-bc84-4693-8471-72c24f9f2340)
Mar 10, 2022 19:00:26.903 [25056] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe' exit code for process 14452 is -1059143458 (intentional termination)
Mar 10, 2022 19:00:26.904 [20356] ERROR - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Recorder: Error 16 (Recording failed. Please check your tuner or antenna.) starting the record, shutting things down.
Mar 10, 2022 19:00:26.904 [20356] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] MediaRecorderVirtual: setting stop time to 2022-03-10 19:00:26
Mar 10, 2022 19:00:26.904 [20356] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Recorder: Asked to stop recording 'Law & Order - E3 - Filtered Life' on channel 5fc76e5a992cb9002eaf825e-5fc705f7088e7d002d4c718e.
Mar 10, 2022 19:00:26.904 [20356] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Grabber: Freed a tuner on device://tv.plex.grabbers.tunerservice/dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6a180070%26rev_04%236%2613882ece%260%26000800e5%23 (now 2 available)
Mar 10, 2022 19:00:26.904 [20356] ERROR - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Recorder: Error 16 (Recording failed. Please check your tuner or antenna.) starting the record, shutting things down.
Mar 10, 2022 19:00:26.904 [20356] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] MediaRecorderVirtual: setting stop time to 2022-03-10 19:00:26
Mar 10, 2022 19:00:26.904 [20356] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Grabber: Operation for 'Law & Order - E3 - Filtered Life' on channel 5fc76e5a992cb9002eaf825e-5fc705f7088e7d002d4c718e completed with status error (Recording failed. Please check your tuner or antenna.)
Mar 10, 2022 19:00:26.904 [20356] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Activity: Ended activity c0dc6e7e-bbaa-4821-bb83-419c611b04f1.
Mar 10, 2022 19:00:26.904 [20356] ERROR - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Starting media grab failed.

But then around 7:32 pm Plex recalculated the recording schedule, saw that this episode wasn’t being recorded and started the recording again.

Mar 10, 2022 19:32:42.005 [5056] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Starting operation for 'Law & Order - E3 - Filtered Life' on channel 5fc76e5a992cb9002eaf825e-5fc705f7088e7d002d4c718e.
Mar 10, 2022 19:32:42.005 [5056] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Activity: registered new activity 70e20100-5449-44e6-a2dd-94bf1942a2c5 - "Recording"
Mar 10, 2022 19:32:42.005 [5056] DEBUG - [Grabber/70cb39112340f97d3d020b17032d2e598127ced5] Grabber: Remote starting a media grab on device device://tv.plex.grabbers.tunerservice/dvb%23bda%23pci%23ven_14f1%26dev_8880%26subsys_6b180070%26rev_04%236%2637178e2d%260%26001000e5%23 (Hauppauge WinTV-quadHD, supported) tuner 0.

I’m not going to post more because it is a lot of log lines, but this time your tuner (perhaps a different one?) was responding in around 1.1sec per call. It started the recording successfully and I think it recorded until 8:00PM. (At least that is when it moved the recording from the .grab folder to your library.)

This COULD be consistent with your theory that one of the tuners is the problem.

I don’t know of any way in Plex to specify which tuner to use. Mine isn’t a Hauppauge, but mine always selects the first (e.g. lowest number tuner) available. I’ll throw this out, more for testing since I don’t really like it: It’s a total hack and might mess you up when you have back-to-back recordings, but I if you set the problem channel recording(s) to begin 1 or more minutes early then it would get going before any other recordings at that time and may end up using a lower number tuner.

Anyone else with ideas?

Edit: Have you disconnected your tuner and/or rebooted your computer? Maybe the tuner is getting slow when it has more concurrent work to do, and needs a reset? I don’t have this tuner, so I’m reaching.

[cantak]

You’re correct. There is a 19:56 long recording. I hadn’t watched it until now - not much point. It is actually the end of the episode. And I have Plex set to strip commercials. So that’s consistent with recording from 7:32pm onwards and a 28 minute recording getting stripped down. I will guess that the good tuner became available at 7:32 after recording a 30 minute show that started at 7:00.

I just deleted each tuner and re-added them and also performed a new scan. Both tuners picked up 11.1 as before. I also installed a Plex server update. Crossing fingers for tomorrow. The show on 11.1 is the first show to be recorded. At 8:00 there are two scheduled recordings. But the half hour show from 7:00 - 7:30 isn’t on tomorrow. So if it works it could be that, the update, or the rescan. Time will tell.