Whilst recording a live TV show with Plex, I was watching the show which had been recorded already (show was already 30 minutes recorded, and I started watching from the beginning of the show 15 minutes late). During the show, Plex suddenly stopped streaming the show. Changing to other TV channels resulted in live TV. Changing back to the desired TV show but chosing to watch live results in LOADING 13% and stuck there. I restarted the PMS service service plexmediaserver restart, but that didn’t help. I manually streamed the TV channel directly from the HD Homerun box with VLC Media Player and it worked just fine.
I did an inspection of the PMS debug logs which I had only enabled after this issue began, and it appears there’s some mpeg errors which I need some help explaining.
Feb 09, 2018 20:49:58.961 [0x7f7aa13cd700] DEBUG - Activity: registered new activity 4f1ef600-6a25-4109-9eb4-10ff2c498422 - Recording
Feb 09, 2018 20:49:58.962 [0x7f7aa13cd700] DEBUG - DVR:Grabber: HDHomerun starting a media grab on device device://tv.plex.grabbers.hdhomerun/10621428 (Silicondust HDHomeRun CONNECT Duo, supported) tuner 1.
Feb 09, 2018 20:49:58.962 [0x7f7aa13cd700] DEBUG - DVR:Grabber: Creating custom segmented recorder for grab for channel 5.2
Feb 09, 2018 20:49:58.962 [0x7f7aa13cd700] DEBUG - HTTP requesting GET http://192.168.0.196:80/lineup.json
Feb 09, 2018 20:49:58.975 [0x7f7aa13cd700] DEBUG - HTTP 200 response from GET http://192.168.0.196:80/lineup.json
Feb 09, 2018 20:49:58.976 [0x7f7aa13cd700] DEBUG - DVR:Segmenter: Creating a new recorder for http://192.168.0.196:5004/auto/v5.2.
Feb 09, 2018 20:49:58.977 [0x7f7aa13cd700] DEBUG - DVR:Grabber: Setting timer to stop grab of ‘Live TV - Session’ in 300 seconds.
Feb 09, 2018 20:49:58.999 [0x7f7aa13cd700] DEBUG - DVR:Grabber: Waiting for a tuner (1 available).
Feb 09, 2018 20:49:58.999 [0x7f7aa13cd700] DEBUG - DVR:Grabber: Allocated a tuner (0 left)
Feb 09, 2018 20:49:59.000 [0x7f7aa13cd700] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Feb 09, 2018 20:49:59.000 [0x7f7aa13cd700] DEBUG - Selecting best audio stream for part ID -1 (autoselect: 0 language: en)
Feb 09, 2018 20:49:59.000 [0x7f7aa13cd700] DEBUG - Audio Stream: -1, Subtitle Stream: -1
Feb 09, 2018 20:49:59.001 [0x7f7aa13cd700] ERROR - Unable to find title for item of type 5
Feb 09, 2018 20:49:59.001 [0x7f7aa13cd700] DEBUG - MDE: analyzing media item -1
Feb 09, 2018 20:49:59.001 [0x7f7aa13cd700] DEBUG - MDE: : no direct play video profile exists for http/mpegts/
Feb 09, 2018 20:49:59.001 [0x7f7aa13cd700] DEBUG - MDE: : no direct play video profile exists for http/mpegts//
Feb 09, 2018 20:49:59.001 [0x7f7aa13cd700] DEBUG - MDE: : codec is unavailable for analysis
Feb 09, 2018 20:49:59.001 [0x7f7aa13cd700] DEBUG - MDE: : codec is unavailable for analysis
Feb 09, 2018 20:49:59.010 [0x7f7aa13cd700] ERROR - Unable to find title for item of type 5
Feb 09, 2018 20:49:59.010 [0x7f7aa13cd700] DEBUG - MDE: : selected media 0 / -1
Feb 09, 2018 20:49:59.010 [0x7f7aa13cd700] DEBUG - Cleaning directory for session 1e574423-e30a-47bc-a96e-fa520b25ca83 ()
Feb 09, 2018 20:49:59.010 [0x7f7aa13cd700] DEBUG - Starting a transcode session 1e574423-e30a-47bc-a96e-fa520b25ca83 at offset -1.0 (state=3)
Feb 09, 2018 20:49:59.010 [0x7f7aa13cd700] DEBUG - Streaming Resource: Added session 0x7f7addc7ede0:1e574423-e30a-47bc-a96e-fa520b25ca83
Feb 09, 2018 20:49:59.013 [0x7f7aa13cd700] DEBUG - Job running: EAE_ROOT=’/tmp/pms-b2fb6f2e-84e3-411c-a7cd-14d31fba7d71/EasyAudioEncoder’ FFMPEG_EXTERNAL_LIBS=’/var/lib/plexmediaserver/Library/Application\ Support/Plex\ Media\ Server/Codecs/b077134-1269-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’ ‘-i’ ‘http://192.168.0.196:5004/auto/v5.2’ ‘-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_copyts’ ‘1’ ‘-segment_time_delta’ ‘0.0625’ ‘-segment_list’ ‘http://127.0.0.1:32400/video/:/transcode/session/1e574423-e30a-47bc-a96e-fa520b25ca83/077658c0-ea2d-4cc1-8776-92371a5909fd/seglist’ ‘-segment_list_type’ ‘csv’ ‘-segment_list_size’ ‘2147483647’ ‘-segment_list_separate_stream_times’ ‘1’ ‘-max_delay’ ‘5000000’ ‘-avoid_negative_ts’ ‘disabled’ ‘-map_metadata’ ‘-1’ ‘-map_chapters’ ‘-1’ ‘media-%05d.ts’ ‘-start_at_zero’ ‘-copyts’ ‘-vsync’ ‘cfr’ ‘-y’ ‘-nostats’ ‘-loglevel’ ‘quiet’ ‘-loglevel_plex’ ‘error’ ‘-progressurl’ ‘http://127.0.0.1:32400/video/:/transcode/session/1e574423-e30a-47bc-a96e-fa520b25ca83/077658c0-ea2d-4cc1-8776-92371a5909fd/progress’
Feb 09, 2018 20:49:59.160 [0x7f7ac4bfc700] DEBUG - Request: [127.0.0.1:55492 (Loopback)] PUT /video/:/transcode/session/74a6ff94-c994-469e-8417-c2a2068f37c2/369e6afa-a303-4964-9907-964e1dbfa6d7/progress?progress=-1.0&size=-22&remaining=-1&speed=1.1 (38 live) Signed-in Token (daniel.t.healy@gmail.com)
Feb 09, 2018 20:49:59.160 [0x7f7adebfe700] DEBUG - Completed: [127.0.0.1:55492] 206 PUT /video/:/transcode/session/74a6ff94-c994-469e-8417-c2a2068f37c2/369e6afa-a303-4964-9907-964e1dbfa6d7/progress?progress=-1.0&size=-22&remaining=-1&speed=1.1 (38 live) 0ms 326 bytes
Feb 09, 2018 20:49:59.525 [0x7f7aa4bd4700] DEBUG - Request: [127.0.0.1:55494 (Loopback)] PUT /video/:/transcode/session/1e574423-e30a-47bc-a96e-fa520b25ca83/077658c0-ea2d-4cc1-8776-92371a5909fd/progress/stream?index=0&id=65&codec=mpeg2video&type=video (38 live) Signed-in Token (daniel.t.healy@gmail.com)
Feb 09, 2018 20:49:59.526 [0x7f7adf3ff700] DEBUG - Completed: [127.0.0.1:55494] 206 PUT /video/:/transcode/session/1e574423-e30a-47bc-a96e-fa520b25ca83/077658c0-ea2d-4cc1-8776-92371a5909fd/progress/stream?index=0&id=65&codec=mpeg2video&type=video (38 live) 0ms 227 bytes
Feb 09, 2018 20:49:59.527 [0x7f7a9cbc4700] DEBUG - Request: [127.0.0.1:55496 (Loopback)] PUT /video/:/transcode/session/1e574423-e30a-47bc-a96e-fa520b25ca83/077658c0-ea2d-4cc1-8776-92371a5909fd/progress/stream?index=1&id=68&codec=ac3&type=audio (38 live) Signed-in Token (daniel.t.healy@gmail.com)
Feb 09, 2018 20:49:59.527 [0x7f7adf3ff700] DEBUG - Completed: [127.0.0.1:55496] 206 PUT /video/:/transcode/session/1e574423-e30a-47bc-a96e-fa520b25ca83/077658c0-ea2d-4cc1-8776-92371a5909fd/progress/stream?index=1&id=68&codec=ac3&type=audio (38 live) 0ms 227 bytes
Feb 09, 2018 20:49:59.545 [0x7f7abbbf2700] ERROR - [Transcoder] [mpeg2video @ 0x1713de0] Invalid frame dimensions 0x0.
Feb 09, 2018 20:49:59.585 [0x7f7abe3fb700] ERROR - [Transcoder] [mpeg2video @ 0x1713de0] Invalid frame dimensions 0x0.
Feb 09, 2018 20:49:59.587 [0x7f7aa4bd4700] ERROR - [Transcoder] [mpeg2video @ 0x1713de0] Invalid frame dimensions 0x0.
Feb 09, 2018 20:49:59.606 [0x7f7ac4bfc700] ERROR - [Transcoder] [mpeg2video @ 0x1713de0] Invalid frame dimensions 0x0.
Feb 09, 2018 20:49:59.676 [0x7f7a9cbc4700] DEBUG - Request: [127.0.0.1:55506 (Loopback)] PUT /video/:/transcode/session/74a6ff94-c994-469e-8417-c2a2068f37c2/369e6afa-a303-4964-9907-964e1dbfa6d7/progress?progress=-1.0&size=-22&remaining=-1&speed=1.0 (39 live) Signed-in Token (daniel.t.healy@gmail.com)
Feb 09, 2018 20:49:59.676 [0x7f7adebfe700] DEBUG - Completed: [127.0.0.1:55506] 206 PUT /video/:/transcode/session/74a6ff94-c994-469e-8417-c2a2068f37c2/369e6afa-a303-4964-9907-964e1dbfa6d7/progress?progress=-1.0&size=-22&remaining=-1&speed=1.0 (39 live) 0ms 326 bytes
Feb 09, 2018 20:49:59.684 [0x7f7abbbf2700] ERROR - [Transcoder] [mpeg2video @ 0x1713de0] Invalid frame dimensions 0x0.
Feb 09, 2018 20:49:59.685 [0x7f7abe3fb700] ERROR - [Transcoder] [mpeg2video @ 0x1713de0] Invalid frame dimensions 0x0.
Feb 09, 2018 20:49:59.706 [0x7f7ac4bfc700] ERROR - [Transcoder] [mpeg2video @ 0x1713de0] Invalid frame dimensions 0x0.
Feb 09, 2018 20:49:59.763 [0x7f7a9cbc4700] DEBUG - Request: [127.0.0.1:55516 (Loopback)] POST /video/:/transcode/session/74a6ff94-c994-469e-8417-c2a2068f37c2/369e6afa-a303-4964-9907-964e1dbfa6d7/seglist (38 live) Signed-in
Feb 09, 2018 20:49:59.799 [0x7f7aa4bd4700] DEBUG - buildLiveM3U8: min 2100 max 6100
Feb 09, 2018 20:49:59.806 [0x7f7abe3fb700] DEBUG - Request: [127.0.0.1:55518 (Loopback)] GET /livetv/sessions/74a6ff94-c994-469e-8417-c2a2068f37c2/9272b1aa-73c7-45dc-bae0-0f7ad7500ee2/06100.ts (39 live) Signed-in
Feb 09, 2018 20:49:59.807 [0x7f7abe3fb700] DEBUG - Content-Length of /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-74a6ff94-c994-469e-8417-c2a2068f37c2/media-06100.ts is 3354108.
Feb 09, 2018 20:49:59.808 [0x7f7abbbf2700] DEBUG - Request: [127.0.0.1:55520 (Loopback)] PUT /video/:/transcode/session/9272b1aa-73c7-45dc-bae0-0f7ad7500ee2/0809aa56-61cd-4e7c-a818-813ed8356ca8/progress?progress=-1.0&size=12397532160&remaining=-1&speed=1.2 (40 live) Signed-in Token (daniel.t.healy@gmail.com)
Feb 09, 2018 20:49:59.809 [0x7f7adf3ff700] DEBUG - Completed: [127.0.0.1:55520] 206 PUT /video/:/transcode/session/9272b1aa-73c7-45dc
