hello,
i’ve been using the dvr feature pretty much since day one. for the most part it has been flawless. the past week or so, most shows fail to record with ‘transcoder error’’ ill usually grab a show mid day, it mostly works fine, and then i grab 3 shows at the same time (quatro tuner) around 11:35pm. this used to work with zero issues… now it rarely does. here is a snippet of some lines in the log that looked interesting. i can post more if required.
it’s a windows 10 desktop pc. decent i5, 32GB ram, ssd for plex install, satas for media. nothing over a network share. the machine is gigabit ethernet attached to a switch where the hdhomerun lives as well.
currently running the latest plex version. i’ve uninstalled/reinstalled. currently going to uninstall and install 1.13.2.5154-fd05be322 as the problem didnt seem to exists when running that version, so maybe it’s a good place to start.
Jul 24, 2018 23:39:08.041 [9260] DEBUG - Completed: [127.0.0.1:50255] 200 GET /livetv/sessions/f97e33b2-69fd-4dcd-a304-bc7c7fd498da/ea16be29-8041-403a-a485-447bfee9c888/index.m3u8?offset=-1.000000 (11 live) 0ms 822 bytes (pipelined: 15)
Jul 24, 2018 23:39:09.045 [9260] VERBOSE - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Jul 24, 2018 23:39:09.045 [9260] VERBOSE - Auth: Came in with the master token, authorization succeeded.
Jul 24, 2018 23:39:09.045 [5384] DEBUG - Request: [127.0.0.1:50255 (Loopback)] GET /livetv/sessions/f97e33b2-69fd-4dcd-a304-bc7c7fd498da/ea16be29-8041-403a-a485-447bfee9c888/index.m3u8?offset=-1.000000 (11 live) Signed-in Token (outz)
Jul 24, 2018 23:39:09.045 [5384] VERBOSE - * User-Agent => Lavf/58.9.100
Jul 24, 2018 23:39:09.045 [5384] VERBOSE - * Accept => /
Jul 24, 2018 23:39:09.045 [5384] VERBOSE - * Connection => keep-alive
Jul 24, 2018 23:39:09.045 [5384] VERBOSE - * Host => 127.0.0.1:32400
Jul 24, 2018 23:39:09.045 [5384] VERBOSE - * Icy-MetaData => 1
Jul 24, 2018 23:39:09.045 [5384] VERBOSE - * X-Plex-Token => xxxxxxxxxxxxxxxxxxxx
Jul 24, 2018 23:39:09.046 [5384] VERBOSE - * offset => -1.000000
Jul 24, 2018 23:39:09.046 [5384] VERBOSE - * X-Plex-Token => xxxxxxxxxxxxxxxxxxxx
Jul 24, 2018 23:39:09.046 [5384] DEBUG - buildLiveM3U8: min 0 max 13 ended 0
Jul 24, 2018 23:39:09.046 [9268] DEBUG - Completed: [127.0.0.1:50255] 200 GET /livetv/sessions/f97e33b2-69fd-4dcd-a304-bc7c7fd498da/ea16be29-8041-403a-a485-447bfee9c888/index.m3u8?offset=-1.000000 (11 live) 0ms 822 bytes (pipelined: 16)
Jul 24, 2018 23:39:10.050 [9268] VERBOSE - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Jul 24, 2018 23:39:10.050 [9268] VERBOSE - Auth: Came in with the master token, authorization succeeded.
Jul 24, 2018 23:39:10.050 [9840] DEBUG - Request: [127.0.0.1:50255 (Loopback)] GET /livetv/sessions/f97e33b2-69fd-4dcd-a304-bc7c7fd498da/ea16be29-8041-403a-a485-447bfee9c888/index.m3u8?offset=-1.000000 (11 live) Signed-in Token (outz)
Jul 24, 2018 23:39:10.050 [9840] VERBOSE - * User-Agent => Lavf/58.9.100
Jul 24, 2018 23:39:10.050 [9840] VERBOSE - * Accept => /
Jul 24, 2018 23:39:10.050 [9840] VERBOSE - * Connection => keep-alive
Jul 24, 2018 23:39:10.050 [9840] VERBOSE - * Host => 127.0.0.1:32400
Jul 24, 2018 23:39:10.050 [9840] VERBOSE - * Icy-MetaData => 1
Jul 24, 2018 23:39:10.050 [9840] VERBOSE - * X-Plex-Token => xxxxxxxxxxxxxxxxxxxx
Jul 24, 2018 23:39:10.051 [9840] VERBOSE - * offset => -1.000000
Jul 24, 2018 23:39:10.051 [9840] VERBOSE - * X-Plex-Token => xxxxxxxxxxxxxxxxxxxx
Jul 24, 2018 23:39:10.051 [9840] DEBUG - buildLiveM3U8: min 0 max 13 ended 0
Jul 24, 2018 23:39:10.051 [9260] DEBUG - Completed: [127.0.0.1:50255] 200 GET /livetv/sessions/f97e33b2-69fd-4dcd-a304-bc7c7fd498da/ea16be29-8041-403a-a485-447bfee9c888/index.m3u8?offset=-1.000000 (11 live) 0ms 822 bytes (pipelined: 17)
Jul 24, 2018 23:39:10.491 [5968] ERROR - [Transcoder] [stream_segment,ssegment @ 037ec540] Failed to open segment list ‘http://127.0.0.1:32400/video/:/transcode/session/f97e33b2-69fd-4dcd-a304-bc7c7fd498da/87a82e65-61f0-46ae-b56d-956d54087134/seglist’
Jul 24, 2018 23:39:10.493 [7084] ERROR - [Transcoder] av_interleaved_write_frame(): Unknown error
Jul 24, 2018 23:39:10.493 [0764] ERROR - [Transcoder] Error writing trailer of media-%05d.ts: Invalid argument
Jul 24, 2018 23:39:10.494 [9260] VERBOSE - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Jul 24, 2018 23:39:10.494 [9260] VERBOSE - Auth: Came in with the master token, authorization succeeded.
Jul 24, 2018 23:39:10.494 [5572] DEBUG - Request: [127.0.0.1:51016 (Loopback)] PUT /video/:/transcode/session/f97e33b2-69fd-4dcd-a304-bc7c7fd498da/87a82e65-61f0-46ae-b56d-956d54087134/progress?progress=-1.0&size=-22&remaining=-1&speed=0.3 (13 live) Signed-in Token (outz)
Jul 24, 2018 23:39:10.494 [5572] VERBOSE - * User-Agent => Lavf/58.9.100
Jul 24, 2018 23:39:10.494 [5572] VERBOSE - * Accept => /
Jul 24, 2018 23:39:10.494 [5572] VERBOSE - * Range => bytes=0-
Jul 24, 2018 23:39:10.494 [5572] VERBOSE - * Connection => close
Jul 24, 2018 23:39:10.494 [5572] VERBOSE - * Host => 127.0.0.1:32400
Jul 24, 2018 23:39:10.495 [5572] VERBOSE - * Icy-MetaData => 1
Jul 24, 2018 23:39:10.495 [5572] VERBOSE - * X-Plex-Token => xxxxxxxxxxxxxxxxxxxx
Jul 24, 2018 23:39:10.495 [5572] VERBOSE - * progress => -1.0
Jul 24, 2018 23:39:10.495 [5572] VERBOSE - * size => -22
Jul 24, 2018 23:39:10.495 [5572] VERBOSE - * remaining => -1
Jul 24, 2018 23:39:10.495 [5572] VERBOSE - * speed => 0.3
Jul 24, 2018 23:39:10.495 [5572] VERBOSE - It took 0.0 sec to serialize a list with 0 elements.
Jul 24, 2018 23:39:10.495 [9260] DEBUG - Completed: [127.0.0.1:51016] 206 PUT /video/:/transcode/session/f97e33b2-69fd-4dcd-a304-bc7c7fd498da/87a82e65-61f0-46ae-b56d-956d54087134/progress?progress=-1.0&size=-22&remaining=-1&speed=0.3 (13 live) 0ms 326 bytes (range: bytes=0-)
Jul 24, 2018 23:39:10.499 [12392] VERBOSE - JobManager: child process with handle 0x1574 exited
Jul 24, 2018 23:39:10.499 [12392] DEBUG - Jobs: ‘C:\Program Files (x86)\Plex\Plex Media Server\PlexTranscoder.exe’ exit code for process 3052 is 1 (failure)
Jul 24, 2018 23:39:11.057 [9260] VERBOSE - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Jul 24, 2018 23:39:11.057 [9260] VERBOSE - Auth: Came in with the master token, authorization succeeded.
Jul 24, 2018 23:39:11.057 [6024] DEBUG - Request: [127.0.0.1:50255 (Loopback)] GET /livetv/sessions/f97e33b2-69fd-4dcd-a304-bc7c7fd498da/ea16be29-8041-403a-a485-447bfee9c888/index.m3u8?offset=-1.000000 (13 live) Signed-in Token (outz)
Jul 24, 2018 23:39:11.057 [6024] VERBOSE - * User-Agent => Lavf/58.9.100
Jul 24, 2018 23:39:11.057 [6024] VERBOSE - * Accept => /
Jul 24, 2018 23:39:11.057 [6024] VERBOSE - * Connection => keep-alive
Jul 24, 2018 23:39:11.057 [6024] VERBOSE - * Host => 127.0.0.1:32400
Jul 24, 2018 23:39:11.057 [6024] VERBOSE - * Icy-MetaData => 1
Jul 24, 2018 23:39:11.057 [6024] VERBOSE - * X-Plex-Token => xxxxxxxxxxxxxxxxxxxx
Jul 24, 2018 23:39:11.058 [6024] VERBOSE - * offset => -1.000000
Jul 24, 2018 23:39:11.058 [6024] VERBOSE - * X-Plex-Token => xxxxxxxxxxxxxxxxxxxx
Jul 24, 2018 23:39:11.058 [6024] DEBUG - buildLiveM3U8: min 0 max 13 ended 1
Jul 24, 2018 23:39:11.058 [9268] DEBUG - Completed: [127.0.0.1:50255] 200 GET /livetv/sessions/f97e33b2-69fd-4dcd-a304-bc7c7fd498da/ea16be29-8041-403a-a485-447bfee9c888/index.m3u8?offset=-1.000000 (13 live) 0ms 837 bytes (pipelined: 18)
Jul 24, 2018 23:39:11.059 [9260] VERBOSE - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Jul 24, 2018 23:39:11.059 [9260] VERBOSE - Auth: Came in with the master token, authorization succeeded.
Jul 24, 2018 23:39:11.059 [9684] DEBUG - Request: [127.0.0.1:51612 (Loopback)] PUT /video/:/transcode/session/ea16be29-8041-403a-a485-447bfee9c888/4c3abefb-b19e-41b3-9065-3a9804fb4105/progress?progress=-1.0&size=15728640&remaining=-1&speed=0.1 (14 live) Signed-in Token (outz)
Jul 24, 2018 23:39:11.059 [9684] VERBOSE - * User-Agent => Lavf/58.9.100
Jul 24, 2018 23:39:11.059 [9684] VERBOSE - * Accept => /
Jul 24, 2018 23:39:11.059 [9684] VERBOSE - * Range => bytes=0-
Jul 24, 2018 23:39:11.059 [9684] VERBOSE - * Connection => close
Jul 24, 2018 23:39:11.059 [9684] VERBOSE - * Host => 127.0.0.1:32400
Jul 24, 2018 23:39:11.059 [9684] VERBOSE - * Icy-MetaData => 1
Jul 24, 2018 23:39:11.059 [9684] VERBOSE - * X-Plex-Token => xxxxxxxxxxxxxxxxxxxx
Jul 24, 2018 23:39:11.059 [9684] VERBOSE - * progress => -1.0
Jul 24, 2018 23:39:11.059 [9684] VERBOSE - * size => 15728640
Jul 24, 2018 23:39:11.059 [9684] VERBOSE - * remaining => -1
Jul 24, 2018 23:39:11.059 [9684] VERBOSE - * speed => 0.1
Jul 24, 2018 23:39:11.059 [9684] VERBOSE - It took 0.0 sec to serialize a list with 0 elements.
Jul 24, 2018 23:39:11.060 [9268] DEBUG - Completed: [127.0.0.1:51612] 206 PUT /video/:/transcode/session/ea16be29-8041-403a-a485-447bfee9c888/4c3abefb-b19e-41b3-9065-3a9804fb4105/progress?progress=-1.0&size=15728640&remaining=-1&speed=0.1 (14 live) 0ms 326 bytes (range: bytes=0-)
Jul 24, 2018 23:39:11.060 [9260] VERBOSE - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Jul 24, 2018 23:39:11.060 [9260] VERBOSE - Auth: Came in with the master token, authorization succeeded.
Jul 24, 2018 23:39:11.060 [9840] DEBUG - Request: [127.0.0.1:52792 (Loopback)] PUT /video/:/transcode/session/ea16be29-8041-403a-a485-447bfee9c888/4c3abefb-b19e-41b3-9065-3a9804fb4105/progress?progress=-1.0&size=15901040&remaining=-1&speed=0.0 (15 live) Signed-in Token (outz)
Jul 24, 2018 23:39:11.060 [9840] VERBOSE - * User-Agent => Lavf/58.9.100
Jul 24, 2018 23:39:11.060 [9840] VERBOSE - * Accept => /
Jul 24, 2018 23:39:11.060 [9840] VERBOSE - * Range => bytes=0-
Jul 24, 2018 23:39:11.060 [9840] VERBOSE - * Connection => close
Jul 24, 2018 23:39:11.060 [9840] VERBOSE - * Host => 127.0.0.1:32400
Jul 24, 2018 23:39:11.061 [9840] VERBOSE - * Icy-MetaData => 1
Jul 24, 2018 23:39:11.061 [9840] VERBOSE - * X-Plex-Token => xxxxxxxxxxxxxxxxxxxx
Jul 24, 2018 23:39:11.061 [9840] VERBOSE - * progress => -1.0
Jul 24, 2018 23:39:11.061 [9840] VERBOSE - * size => 15901040
Jul 24, 2018 23:39:11.061 [9840] VERBOSE - * remaining => -1
Jul 24, 2018 23:39:11.061 [9840] VERBOSE - * speed => 0.0
Jul 24, 2018 23:39:11.061 [9840] VERBOSE - It took 0.0 sec to serialize a list with 0 elements.
Jul 24, 2018 23:39:11.061 [9268] DEBUG - Completed: [127.0.0.1:52792] 206 PUT /video/:/transcode/session/ea16be29-8041-403a-a485-447bfee9c888/4c3abefb-b19e-41b3-9065-3a9804fb4105/progress?progress=-1.0&size=15901040&remaining=-1&speed=0.0 (15 live) 0ms 326 bytes (range: bytes=0-)
Jul 24, 2018 23:39:11.066 [12528] VERBOSE - JobManager: child process with handle 0xf34 exited
Jul 24, 2018 23:39:11.066 [12528] DEBUG - Jobs: ‘C:\Program Files (x86)\Plex\Plex Media Server\PlexTranscoder.exe’ exit code for process 11048 is 0 (success)
Jul 24, 2018 23:39:11.066 [0212] DEBUG - DVR:Recorder: Asked to stop recording ‘Jimmy Kimmel Live - E99 - Mandy Moore; Elsie Fisher; Dennis Lloyd’.
Jul 24, 2018 23:39:11.066 [0212] DEBUG - DVR:Recorder: Stopping transcode session.
Jul 24, 2018 23:39:11.066 [0212] DEBUG - Killing job.
Jul 24, 2018 23:39:11.066 [0212] DEBUG - Job was already killed, not killing again.
Jul 24, 2018 23:39:11.066 [0212] DEBUG - Stopping transcode session f97e33b2-69fd-4dcd-a304-bc7c7fd498da
Jul 24, 2018 23:39:11.066 [10212] DEBUG - Cleaning directory for session f97e33b2-69fd-4dcd-a304-bc7c7fd498da (C:\Users\outz\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-f97e33b2-69fd-4dcd-a304-bc7c7fd498da)
Jul 24, 2018 23:39:11.084 [0212] DEBUG - DVR:Grabber: Freed a tuner (now 4 available)
Jul 24, 2018 23:39:11.084 [0212] DEBUG - DVR:Grabber: Recording for ‘Jimmy Kimmel Live - E99 - Mandy Moore; Elsie Fisher; Dennis Lloyd’ stopped with status error.
Jul 24, 2018 23:39:11.084 [0212] DEBUG - DVR:Grabber: Using recording status.
