Server Version#: 1.32.8.7639
Player Version#: 4.116.1
Tuner Make/Model: HDHomerun EXTEND HDTC-2US
Guide/Lineup name: N/A
Using XMLTV?: No
Channel number/Name: All
I re-signed up for Plex Pass to get access to live TV again. This worked fine a few years ago when I did this before.
HDHomeRun device is on 192.168.50.205
Client is on 10.168.50.24
Pretty simple setup. The tuner settings for the EXTEND have it set to use “Highest Quality” transcode profile, which presumably maps to the “heavy” transcode profile used by the EXTEND. The Plex Support article for that specific tuner indicates I should use “Highest Quality” if I intend to use the built-in transcoder.
When I go to the Live TV section and click "Play’ next to a channel, I get a pop-up “Source is unavailable”. Here’s what I am seeing in the console. It appears it may be trying to transcode my already transcoded video it gets from the HDHomeRun EXTEND.
`Jan 17, 2024 18:34:19.668 [139777866099512] Debug — Request: [192.168.50.24:60347 (WAN)] GET /:/timeline?ratingKey=29361&key=%2Flibrary%2Fmetadata%2F29361&playbackTime=0&playQueueItemID=85578&state=paused&hasMDE=1&time=146000&duration=1298000 (8 live) #86e3 GZIP Signed-in Token (tendonut) (Chrome)
Jan 17, 2024 18:34:19.669 [139777866099512] Debug — [Req#86e3] Client [42d7rvfl3fjok5r4p3oy4ci1] reporting timeline state paused, progress of 146000/1298000ms for guid=, playbackTime=0ms ratingKey=29361 url=, key=/library/metadata/29361, containerKey=, metadataId=29361, source=
Jan 17, 2024 18:34:19.670 [139777866099512] Debug — [Req#86e3] [Now] User is tendonut (ID: 1)
Jan 17, 2024 18:34:19.670 [139777866099512] Debug — [Req#86e3] [Now] Device is Chrome (Chrome).
Jan 17, 2024 18:34:19.670 [139777866099512] Debug — [Req#86e3] [Now] Profile is Web
Jan 17, 2024 18:34:19.670 [139777866099512] Debug — [Req#86e3] [Now] Updated play state for /library/metadata/29361.
Jan 17, 2024 18:34:19.670 [139777866099512] Debug — [Req#86e3] Statistics: (t3v6kut43pj9i7ja1pxwpd1j) Reporting active playback in state 1 of type 4 (scrobble: 0) for account 1
Jan 17, 2024 18:34:19.671 [139778054867768] Debug — Completed: [192.168.50.24:60347] 200 GET /:/timeline?ratingKey=29361&key=%2Flibrary%2Fmetadata%2F29361&playbackTime=0&playQueueItemID=85578&state=paused&hasMDE=1&time=146000&duration=1298000 (9 live) #86e3 GZIP 2ms 779 bytes (pipelined: 33)
Jan 17, 2024 18:34:19.672 [139777884027704] Debug — Request: [192.168.50.24:60493 (WAN)] GET /video/:/transcode/universal/ping?session=2nj446xss7f1ovy01njpcqm3 (9 live) #8704 GZIP Signed-in Token (tendonut) (Chrome)
Jan 17, 2024 18:34:19.672 [139777884027704] Debug — [Req#8704/Transcode] [Now] Freshening session based on ping for 42d7rvfl3fjok5r4p3oy4ci1_Video
Jan 17, 2024 18:34:19.672 [139777884027704] Debug — [Req#8704/Transcode] Received transcode session ping for session 2nj446xss7f1ovy01njpcqm3.
Jan 17, 2024 18:34:19.672 [139778052758328] Debug — Completed: [192.168.50.24:60493] 200 GET /video/:/transcode/universal/ping?session=2nj446xss7f1ovy01njpcqm3 (9 live) #8704 GZIP 0ms 195 bytes (pipelined: 1)
Jan 17, 2024 18:34:28.640 [139777895099192] Debug — Request: [192.168.50.24:60493 (WAN)] POST /livetv/dvrs/10/channels/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9/tune (9 live) #8725 GZIP Signed-in Token (tendonut) (Chrome)
Jan 17, 2024 18:34:28.641 [139777895099192] Debug — [Req#8725] Subscription: Starting a new rolling subscription for session wpfb5y0f4elmtd2m3dt8xn6j channel 5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9.
Jan 17, 2024 18:34:28.642 [139777950051128] Debug — Request: [192.168.50.24:60347 (WAN)] GET /web/static/b38■■■9594ac52d049ba.png (9 live) #8720 GZIP Signed-in
Jan 17, 2024 18:34:28.642 [139777950051128] Debug — [Req#8720] Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-fb6452ebf/WebClient.bundle/Contents/Resources/static/b38■■■9594ac52d049ba.png"
Jan 17, 2024 18:34:28.642 [139777950051128] Debug — Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-fb6452ebf/WebClient.bundle/Contents/Resources/static/b38■■■9594ac52d049ba.png is 54413 (of total: 54413).
Jan 17, 2024 18:34:28.642 [139778054867768] Debug — Completed: [192.168.50.24:60347] 200 GET /web/static/b38■■■9594ac52d049ba.png (9 live) #8720 GZIP 0ms 54413 bytes (pipelined: 34)
Jan 17, 2024 18:34:28.651 [139777895099192] Debug — [Req#8725] Activity: registered new activity 51671faa-b751-4a92-8967-46b24faf65f0 - "Refreshing Sub"
Jan 17, 2024 18:34:28.652 [139777895099192] Debug — [Req#8725] Activity: updated activity 51671faa-b751-4a92-8967-46b24faf65f0 - completed 0.0% - Refreshing Sub
Jan 17, 2024 18:34:28.652 [139777895099192] Debug — [Req#8725] Activity: updated activity 51671faa-b751-4a92-8967-46b24faf65f0 - completed 0.0% - Refreshing Sub
Jan 17, 2024 18:34:28.652 [139777895099192] Debug — [Req#8725] Activity: updated activity 51671faa-b751-4a92-8967-46b24faf65f0 - completed 0.0% - Refreshing Sub
Jan 17, 2024 18:34:28.652 [139777895099192] Debug — [Req#8725] Activity: updated activity 51671faa-b751-4a92-8967-46b24faf65f0 - completed 0.0% - Refreshing Sub
Jan 17, 2024 18:34:28.652 [139777895099192] Debug — [Req#8725] Activity: registered new activity 0ca736c9-c831-4d5b-a062-82f695ef7d7a - "Processing subscriptions"
Jan 17, 2024 18:34:28.652 [139777895099192] Debug — [Req#8725] Subscription: Scheduling subscriptions.
Jan 17, 2024 18:34:28.653 [139777895099192] Debug — [Req#8725] Subscription: Initialized 1 schedulers.
Jan 17, 2024 18:34:28.653 [139777895099192] Debug — [Req#8725] Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Jan 17, 2024 18:34:28.653 [139777895099192] Debug — [Req#8725] DVR:NewSchedule: Partitioning, step one, let's compute an absolute ordering.
Jan 17, 2024 18:34:28.653 [139777895099192] Debug — [Req#8725] DVR:NewSchedule: Between 2024-01-17 18:34:28 and 2024-01-17 18:39:28 on channel 5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9: 'Live TV - Session'
Jan 17, 2024 18:34:28.653 [139777895099192] Debug — [Req#8725] DVR:NewSchedule: Initial partitioning completed with 1 partitions.
Jan 17, 2024 18:34:28.653 [139777895099192] Debug — [Req#8725] DVR:NewSchedule: Partitioning completed with 1 partitions.
Jan 17, 2024 18:34:28.653 [139777895099192] Debug — [Req#8725] DVR:NewSchedule: PARTITION of size 1.
Jan 17, 2024 18:34:28.653 [139777895099192] Debug — [Req#8725] DVR:NewSchedule: Between 2024-01-17 18:34:28 and 2024-01-17 18:39:28 on channel 5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9: 'Live TV - Session'
Jan 17, 2024 18:34:28.653 [139777895099192] Debug — [Req#8725] DVR:NewSchedule: After processing the partition, we have 2 alternate realities.
Jan 17, 2024 18:34:28.653 [139777895099192] Debug — [Req#8725] DVR:NewSchedule: We computed 2 alternative realities in 0 ms.
Jan 17, 2024 18:34:28.653 [139777895099192] Debug — [Req#8725] DVR:NewSchedule: The winning schedule had score: 10 and 0 conflicts.
Jan 17, 2024 18:34:28.653 [139777895099192] Debug — [Req#8725] DVR:NewSchedule: device 0 tuner 1 airing 0 between 2024-01-17 18:34:28 and 2024-01-17 18:39:28 on channel 5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9: 'Live TV - Session'
Jan 17, 2024 18:34:28.653 [139777895099192] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Starting grab immediately for 'Live TV - Session wpfb5y0f4elmtd2m3dt8xn6j' on channel 5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9 and we're 0 seconds in already.
Jan 17, 2024 18:34:28.654 [139777895099192] Debug — [Req#8725] Subscription: Scheduled all subscriptions in 1 ms.
Jan 17, 2024 18:34:28.654 [139777895099192] Debug — [Req#8725] Activity: Ended activity 0ca736c9-c831-4d5b-a062-82f695ef7d7a.
Jan 17, 2024 18:34:28.654 [139777895099192] Debug — [Req#8725] Subscription: There are 1 active grabs at the end.
Jan 17, 2024 18:34:28.654 [139777895099192] Debug — [Req#8725] Subscription: Waiting for media grab to start.
Jan 17, 2024 18:34:28.654 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Starting operation for 'Live TV - Session wpfb5y0f4elmtd2m3dt8xn6j' on channel 5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9.
Jan 17, 2024 18:34:28.654 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Activity: registered new activity 3ca5ecdf-8f22-4e0d-bd1d-491fb0d899b3 - "Recording"
Jan 17, 2024 18:34:28.655 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Grabber: HDHomerun starting a media grab on device device://tv.plex.grabbers.hdhomerun/10584760 (Unknown HDHomeRun EXTEND, supported) tuner 1.
Jan 17, 2024 18:34:28.655 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Grabber: Creating custom segmented recorder for grab for channel 5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9
Jan 17, 2024 18:34:28.655 [139777903536952] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j/HCl#20d] HTTP requesting GET http://192.168.50.205/discover.json
Jan 17, 2024 18:34:28.655 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j/HCl#20e] HTTP requesting GET http://192.168.50.205/lineup.json
Jan 17, 2024 18:34:28.680 [139778011884344] Debug — [HttpClient/HCl#20d] HTTP/1.1 (0.0s) 200 response from GET http://192.168.50.205/discover.json
Jan 17, 2024 18:34:28.680 [139777903536952] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j/HCl#20f] HTTP requesting GET http://192.168.50.205/lineup_status.json
Jan 17, 2024 18:34:28.697 [139778011884344] Debug — [HttpClient/HCl#20e] HTTP/1.1 (0.0s) 200 response from GET http://192.168.50.205/lineup.json
Jan 17, 2024 18:34:28.697 [139778011884344] Debug — [HttpClient/HCl#20f] HTTP/1.1 (0.0s) 200 response from GET http://192.168.50.205/lineup_status.json
Jan 17, 2024 18:34:28.697 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] DVR:Segmenter: Creating a new recorder for http://192.168.50.205:5004/auto/v4.2?transcode=heavy.
Jan 17, 2024 18:34:28.698 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Setting timer to stop grab of 'Live TV - Session wpfb5y0f4elmtd2m3dt8xn6j' on channel 5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9 in 300 seconds.
Jan 17, 2024 18:34:28.698 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Grabber: Waiting for a tuner on device://tv.plex.grabbers.hdhomerun/10584760 (2 available) for at most 300 seconds.
Jan 17, 2024 18:34:28.698 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Grabber: Allocated a tuner on device://tv.plex.grabbers.hdhomerun/10584760 (1 left)
Jan 17, 2024 18:34:28.698 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] We're going to try to auto-select an audio stream for account 1.
Jan 17, 2024 18:34:28.698 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Selecting best audio stream for part ID -1 (language: en)
Jan 17, 2024 18:34:28.698 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] We're going to try to auto-select a subtitle.
Jan 17, 2024 18:34:28.698 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Subtitles: Found a candidate subtitle language [] for a foreign film
Jan 17, 2024 18:34:28.698 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Audio Stream: -1, Subtitle Stream: -1
Jan 17, 2024 18:34:28.698 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] MDE: Selected protocol hls; container: mpegts
Jan 17, 2024 18:34:28.698 [139777990601528] Error — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Unable to find title for item of type 5
Jan 17, 2024 18:34:28.698 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] MDE: analyzing media item -1
Jan 17, 2024 18:34:28.698 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] MDE: : no direct play video profile exists for http/mpegts/
Jan 17, 2024 18:34:28.698 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] MDE: : no direct play video profile exists for http/mpegts//
Jan 17, 2024 18:34:28.698 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] MDE: : selected subtitle stream has no codec
Jan 17, 2024 18:34:28.698 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] MDE: : codec is unavailable for analysis
Jan 17, 2024 18:34:28.698 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] MDE: : codec is unavailable for analysis
Jan 17, 2024 18:34:28.698 [139777990601528] Error — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Unable to find title for item of type 5
Jan 17, 2024 18:34:28.698 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] MDE: : selected media 0 / -1
Jan 17, 2024 18:34:28.698 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Cleaning directory for session 64a69a25-a7db-42fa-bb39-5b3a5c7a2aee ()
Jan 17, 2024 18:34:28.699 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Starting a transcode session 64a69a25-a7db-42fa-bb39-5b3a5c7a2aee at offset -1.0 (state=3)
Jan 17, 2024 18:34:28.699 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Streaming Resource: Added session 0x7f209d679b28:64a69a25-a7db-42fa-bb39-5b3a5c7a2aee
Jan 17, 2024 18:34:28.699 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j/JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/90a317c-4653-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Transcoder" -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://192.168.50.205:5004/auto/v4.2?transcode=heavy" -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/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/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/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress
Jan 17, 2024 18:34:28.699 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j/JobRunner] In directory: "/config/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-64a69a25-a7db-42fa-bb39-5b3a5c7a2aee"
Jan 17, 2024 18:34:28.699 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j/JobRunner] Jobs: Starting child process with pid 571
Jan 17, 2024 18:34:28.702 [139777879808824] Debug — Request: [127.0.0.1:38360 (Loopback)] PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress?status=startup (10 live) #871f Signed-in Token (tendonut) (range: bytes=0-)
Jan 17, 2024 18:34:28.702 [139778052758328] Debug — Completed: [127.0.0.1:38360] 204 PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress?status=startup (10 live) #871f 0ms 203 bytes (pipelined: 1) (range: bytes=0-)
Jan 17, 2024 18:34:28.704 [139777863990072] Debug — Request: [127.0.0.1:38360 (Loopback)] PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress?status=startup (10 live) #8807 Signed-in Token (tendonut) (range: bytes=0-)
Jan 17, 2024 18:34:28.705 [139778054867768] Debug — Completed: [127.0.0.1:38360] 204 PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress?status=startup (10 live) #8807 0ms 203 bytes (pipelined: 2) (range: bytes=0-)
Jan 17, 2024 18:34:28.705 [139777866099512] Debug — Request: [127.0.0.1:38360 (Loopback)] PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress?status=opening (10 live) #880c Signed-in Token (tendonut) (range: bytes=0-)
Jan 17, 2024 18:34:28.705 [139778054867768] Debug — Completed: [127.0.0.1:38360] 204 PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress?status=opening (10 live) #880c 0ms 203 bytes (pipelined: 3) (range: bytes=0-)
Jan 17, 2024 18:34:29.617 [139777879808824] Debug — Request: [192.168.50.24:60347 (WAN)] GET /:/timeline?ratingKey=29361&key=%2Flibrary%2Fmetadata%2F29361&playbackTime=0&playQueueItemID=85578&state=paused&hasMDE=1&time=146000&duration=1298000 (10 live) #8754 GZIP Signed-in Token (tendonut) (Chrome)
Jan 17, 2024 18:34:29.618 [139777879808824] Debug — [Req#8754] Client [42d7rvfl3fjok5r4p3oy4ci1] reporting timeline state paused, progress of 146000/1298000ms for guid=, playbackTime=0ms ratingKey=29361 url=, key=/library/metadata/29361, containerKey=, metadataId=29361, source=
Jan 17, 2024 18:34:29.618 [139777879808824] Debug — [Req#8754] [Now] User is tendonut (ID: 1)
Jan 17, 2024 18:34:29.618 [139777879808824] Debug — [Req#8754] [Now] Device is Chrome (Chrome).
Jan 17, 2024 18:34:29.619 [139777879808824] Debug — [Req#8754] [Now] Profile is Web
Jan 17, 2024 18:34:29.619 [139777879808824] Debug — [Req#8754] [Now] Updated play state for /library/metadata/29361.
Jan 17, 2024 18:34:29.619 [139777879808824] Debug — [Req#8754] Statistics: (t3v6kut43pj9i7ja1pxwpd1j) Reporting active playback in state 1 of type 4 (scrobble: 0) for account 1
Jan 17, 2024 18:34:29.620 [139778052758328] Debug — Completed: [192.168.50.24:60347] 200 GET /:/timeline?ratingKey=29361&key=%2Flibrary%2Fmetadata%2F29361&playbackTime=0&playQueueItemID=85578&state=paused&hasMDE=1&time=146000&duration=1298000 (10 live) #8754 GZIP 2ms 779 bytes (pipelined: 35)
Jan 17, 2024 18:34:29.771 [139777884027704] Debug — Request: [127.0.0.1:38360 (Loopback)] PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress?status=opened (10 live) #8811 Signed-in Token (tendonut) (range: bytes=0-)
Jan 17, 2024 18:34:29.771 [139778054867768] Debug — Completed: [127.0.0.1:38360] 204 PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress?status=opened (10 live) #8811 0ms 203 bytes (pipelined: 4) (range: bytes=0-)
Jan 17, 2024 18:34:29.771 [139777888246584] Debug — Request: [127.0.0.1:38360 (Loopback)] PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress/stream?index=0&id=65&codec=h264&type=video (10 live) #882f Signed-in Token (tendonut) (range: bytes=0-)
Jan 17, 2024 18:34:29.771 [139778054867768] Debug — Completed: [127.0.0.1:38360] 200 PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress/stream?index=0&id=65&codec=h264&type=video (10 live) #882f 0ms 195 bytes (pipelined: 5) (range: bytes=0-)
Jan 17, 2024 18:34:29.772 [139777977461560] Debug — Request: [127.0.0.1:38360 (Loopback)] PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress/stream?index=1&id=68&codec=ac3&type=audio (10 live) #8834 Signed-in Token (tendonut) (range: bytes=0-)
Jan 17, 2024 18:34:29.772 [139778052758328] Debug — Completed: [127.0.0.1:38360] 200 PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress/stream?index=1&id=68&codec=ac3&type=audio (10 live) #8834 0ms 195 bytes (pipelined: 6) (range: bytes=0-)
Jan 17, 2024 18:34:30.813 [139777905646392] Debug — [Req#401] Subscription: Moving end of rolling sub for 'Live TV - Session wpfb5y0f4elmtd2m3dt8xn6j' on channel 5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9 to 2024-01-17 18:39:30.
Jan 17, 2024 18:34:30.813 [139777905646392] Debug — [Req#401] Activity: registered new activity 5f6bb24c-5a8c-4fa4-a57b-d8aaeccebcf5 - "Processing subscriptions"
Jan 17, 2024 18:34:30.813 [139777905646392] Debug — [Req#401] Subscription: Scheduling subscriptions.
Jan 17, 2024 18:34:30.814 [139777905646392] Debug — [Req#401] Subscription: Initialized 1 schedulers.
Jan 17, 2024 18:34:30.814 [139777905646392] Debug — [Req#401] DVR:NewSchedule: Scheduled an operation 'Live TV - Session wpfb5y0f4elmtd2m3dt8xn6j' on channel 5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9 on device 0 tuner 1 between 2024-01-17 18:34:28 and 2024-01-17 18:39:30
Jan 17, 2024 18:34:30.814 [139777905646392] Debug — [Req#401/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Setting timer to stop grab of 'Live TV - Session wpfb5y0f4elmtd2m3dt8xn6j' on channel 5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9 in 300 seconds.
Jan 17, 2024 18:34:30.814 [139777905646392] Debug — [Req#401] Subscription: Starting with 1 grabs, scheduled 1 active ones preemptively.
Jan 17, 2024 18:34:30.814 [139777905646392] Debug — [Req#401] DVR:NewSchedule: Partitioning, step one, let's compute an absolute ordering.
Jan 17, 2024 18:34:30.814 [139777905646392] Debug — [Req#401] DVR:NewSchedule: Initial partitioning completed with 0 partitions.
Jan 17, 2024 18:34:30.814 [139777905646392] Debug — [Req#401] DVR:NewSchedule: Partitioning completed with 0 partitions.
Jan 17, 2024 18:34:30.814 [139777905646392] Debug — [Req#401] DVR:NewSchedule: We computed 1 alternative realities in 0 ms.
Jan 17, 2024 18:34:30.814 [139777905646392] Debug — [Req#401] DVR:NewSchedule: The winning schedule had score: 0 and 0 conflicts.
Jan 17, 2024 18:34:30.814 [139777905646392] Debug — [Req#401] Subscription: Scheduled all subscriptions in 1 ms.
Jan 17, 2024 18:34:30.844 [139777905646392] Debug — [Req#401] Activity: Ended activity 5f6bb24c-5a8c-4fa4-a57b-d8aaeccebcf5.
Jan 17, 2024 18:34:30.849 [139777905646392] Debug — [Req#401] Subscription: There are 1 active grabs at the end.
Jan 17, 2024 18:34:30.870 [139777947941688] Debug — Request: [127.0.0.1:38360 (Loopback)] PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress/streamDetail?index=0&id=65&codec=h264&type=video&profile=High&width=704&height=480&interlaced=1&sar=40:33&level=40&frameRate=29.970&closedCaptions=1 (10 live) #8839 Signed-in Token (tendonut) (range: bytes=0-)
Jan 17, 2024 18:34:30.870 [139778052758328] Debug — Completed: [127.0.0.1:38360] 200 PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress/streamDetail?index=0&id=65&codec=h264&type=video&profile=High&width=704&height=480&interlaced=1&sar=40:33&level=40&frameRate=29.970&closedCaptions=1 (10 live) #8839 0ms 195 bytes (pipelined: 7) (range: bytes=0-)
Jan 17, 2024 18:34:30.870 [139777899318072] Debug — Request: [127.0.0.1:38360 (Loopback)] PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress/streamDetail?index=1&id=68&codec=ac3&type=audio&bitrate=192000&channels=2&layout=stereo&sampleRate=48000 (10 live) #886a Signed-in Token (tendonut) (range: bytes=0-)
Jan 17, 2024 18:34:30.870 [139778054867768] Debug — Completed: [127.0.0.1:38360] 200 PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress/streamDetail?index=1&id=68&codec=ac3&type=audio&bitrate=192000&channels=2&layout=stereo&sampleRate=48000 (10 live) #886a 0ms 195 bytes (pipelined: 8) (range: bytes=0-)
Jan 17, 2024 18:34:30.871 [139777877699384] Debug — Request: [127.0.0.1:38360 (Loopback)] PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress?duration=-1.000000 (10 live) #886f Signed-in Token (tendonut) (range: bytes=0-)
Jan 17, 2024 18:34:30.871 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Using default transcode duration of 120 minutes
Jan 17, 2024 18:34:30.871 [139778052758328] Debug — Completed: [127.0.0.1:38360] 204 PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress?duration=-1.000000 (10 live) #886f 0ms 203 bytes (pipelined: 9) (range: bytes=0-)
Jan 17, 2024 18:34:30.871 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Started session successfully: 64a69a25-a7db-42fa-bb39-5b3a5c7a2aee
Jan 17, 2024 18:34:30.871 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Recorder: It took 2.2 sec to start the session for http://192.168.50.205:5004/auto/v4.2?transcode=heavy
Jan 17, 2024 18:34:30.871 [139777990601528] Error — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Unknown metadata type:
Jan 17, 2024 18:34:30.871 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Asked for unknown type: -1
Jan 17, 2024 18:34:30.871 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Grabber: Merging EPG item with live item.
Jan 17, 2024 18:34:30.871 [139777901427512] Debug — Request: [127.0.0.1:38360 (Loopback)] PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress?width=853&height=480 (10 live) #8874 Signed-in Token (tendonut) (range: bytes=0-)
Jan 17, 2024 18:34:30.872 [139778054867768] Debug — Completed: [127.0.0.1:38360] 206 PUT /video/:/transcode/session/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5/progress?width=853&height=480 (10 live) #8874 0ms 355 bytes (pipelined: 10) (range: bytes=0-)
Jan 17, 2024 18:34:30.873 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Added new metadata item (Live Session wpfb5y0f4elmtd2m3dt8xn6j) with ID 33087
Jan 17, 2024 18:34:30.873 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Updating metadata item (save) (Live Session wpfb5y0f4elmtd2m3dt8xn6j) with ID 33087
Jan 17, 2024 18:34:30.874 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Updating part with ID=43139 []
Jan 17, 2024 18:34:30.874 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Updating metadata item (save) (Me Time; Zadie and the Big, Loud, Noisy Thing) with ID 33087
Jan 17, 2024 18:34:30.875 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Updating metadata item (save) (Me Time; Zadie and the Big, Loud, Noisy Thing) with ID 33087
Jan 17, 2024 18:34:30.876 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Updating media item 36636, size=0, metadata_item_id=33087
Jan 17, 2024 18:34:30.876 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Updating part with ID=43139 []
Jan 17, 2024 18:34:30.877 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Updating part with ID=43139 []
Jan 17, 2024 18:34:30.877 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] We're going to try to auto-select an audio stream for account 1.
Jan 17, 2024 18:34:30.877 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Selecting best audio stream for part ID 43139 (language: en)
Jan 17, 2024 18:34:30.877 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] We're going to try to auto-select a subtitle.
Jan 17, 2024 18:34:30.877 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Subtitles: Found a candidate subtitle language [] for a foreign film
Jan 17, 2024 18:34:30.877 [139777990601528] Debug — [Req#8725/Grabber/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9-wpfb5y0f4elmtd2m3dt8xn6j] Audio Stream: 81498, Subtitle Stream: 81499
Jan 17, 2024 18:34:30.878 [139777895099192] Debug — [Req#8725] Subscription: Waiting for media recorder to start.
Jan 17, 2024 18:34:30.878 [139777895099192] Debug — [Req#8725] Subscription: Rolling sub for channel 5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9 started successfully.
Jan 17, 2024 18:34:30.898 [139778052758328] Debug — Completed: [192.168.50.24:60493] 200 POST /livetv/dvrs/10/channels/5fc76b3aa62668002dead83a-5fc705fc678e02002eccffd9/tune (10 live) #8725 GZIP 2257ms 19210 bytes (pipelined: 2)
Jan 17, 2024 18:34:30.906 [139777879808824] Debug — Request: [192.168.50.24:60493 (WAN)] GET /status/sessions/background (10 live) #88c2 GZIP Signed-in Token (tendonut) (Chrome)
Jan 17, 2024 18:34:30.907 [139778052758328] Debug — Completed: [192.168.50.24:60493] 200 GET /status/sessions/background (10 live) #88c2 GZIP 0ms 397 bytes (pipelined: 3)
Jan 17, 2024 18:34:30.911 [139777863990072] Debug — Request: [192.168.50.24:60493 (WAN)] GET /status/sessions/background (10 live) #88ce GZIP Signed-in Token (tendonut) (Chrome)
Jan 17, 2024 18:34:30.911 [139778052758328] Debug — Completed: [192.168.50.24:60493] 200 GET /status/sessions/background (10 live) #88ce GZIP 0ms 397 bytes (pipelined: 4)
Jan 17, 2024 18:34:30.915 [139777866099512] Debug — Request: [192.168.50.24:60493 (WAN)] GET /status/sessions/background (10 live) #88d3 GZIP Signed-in Token (tendonut) (Chrome)
Jan 17, 2024 18:34:30.916 [139778052758328] Debug — Completed: [192.168.50.24:60493] 200 GET /status/sessions/background (10 live) #88d3 GZIP 0ms 397 bytes (pipelined: 5)
Jan 17, 2024 18:34:30.928 [139777884027704] Debug — Request: [192.168.50.24:60493 (WAN)] GET /photo/:/transcode?width=1920&height=911&minSize=1&opacity=10&background=343a3f&url=https%3A%2F%2Fmetadata-static.plex.tv%2F1%2Fgracenote%2F13a5199ab86d5da2f6d3d07e7e50d11e.jpg (10 live) #88d8 GZIP Signed-in Token (tendonut) (Chrome)
Jan 17, 2024 18:34:30.931 [139777884027704] Debug — [Req#88d8] Photo transcoder: Request for url [https://metadata-static.plex.tv/1/gracenote/13a5199ab86d5da2f6d3d07e7e50d11e.jpg] (is local: 0 upscaled: 0)
Jan 17, 2024 18:34:30.933 [139777884027704] Debug — [Req#88d8/HCl#211] HTTP requesting GET https://metadata-static.plex.tv/1/gracenote/13a5199ab86d5da2f6d3d07e7e50d11e.jpg
Jan 17, 2024 18:34:31.005 [139777888246584] Debug — Request: [192.168.50.24:60347 (WAN)] GET /:/timeline?ratingKey=33087&key=%2Flivetv%2Fsessions%2F64a69a25-a7db-42fa-bb39-5b3a5c7a2aee&playbackTime=0&state=error&hasMDE=1&airingID=481&time=0&duration=1800000 (10 live) #882a GZIP Signed-in Token (tendonut) (Chrome)
Jan 17, 2024 18:34:31.005 [139777888246584] Warning — [Req#882a] Could not convert "state" ("error") to the correct type
Jan 17, 2024 18:34:31.005 [139778052758328] Debug — Completed: [192.168.50.24:60347] 400 GET /:/timeline?ratingKey=33087&key=%2Flivetv%2Fsessions%2F64a69a25-a7db-42fa-bb39-5b3a5c7a2aee&playbackTime=0&state=error&hasMDE=1&airingID=481&time=0&duration=1800000 (10 live) #882a GZIP 0ms 384 bytes (pipelined: 36)
Jan 17, 2024 18:34:31.086 [139777977461560] Debug — [Req#8804/Transcode/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5] Transcoder segment range: 0 - 0 (-1)
Jan 17, 2024 18:34:31.160 [139777947941688] Debug — [Req#88e8/Transcode/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5] Transcoder segment range: 0 - 0 (0)
Jan 17, 2024 18:34:31.160 [139777899318072] Debug — [Req#88ec/Transcode/64a69a25-a7db-42fa-bb39-5b3a5c7a2aee/cda8f6a9-38d1-478f-ab21-c8cbe07907a5] Transcoder segment range: 0 - 1 (0)`