@thehritzinator said:
Still having issues with this…Anybody find a solution yet?
Is it to do with music or other media now?
Would need diagnostics - as outlined in my earlier post
Still the same issues as I described in the original post…It’s all different media. I only had the music one for a short time when I first got Plex pass to check it out, which was like 2 months after the issues started, so it’s not music related. I replied to your earlier post like a month ago and never got a response, that’s why I re-asked if anyone had found a solution, to kind of bump the thread lol, was hoping you’d see it and thought maybe you’d forgotten about it. As I outlined in my OP and in the comment, I cannot replicate; It is random. I’ve tried to do 5 min clips, but the short clips don’t seem to reproduce the error, because the actual video file doesn’t crash every single time, just randomly sometimes.
I am positive this is a Plex issue, not a file issue. Files that worked fine 100% of the time last year, now randomly have issues I had never even seen this error until November, and I’ve used Plex since 2012. I watched full series of tv shows with no issues, now some have this error. Nothing changed for the media (same files on same drives since last year) The exact same files do not give me issues when using VLC, Emby, or Kodi. That’s why I believe it to be a Plex issue. It started in November and no other software gives me these errors.
It really seems like it’s the audio conversion that makes the transcoder have errors. Any AC3 5.1 → AAC Stereo seems to be causing issues. For the most part, I have verrrry few issues with the rest of my media collection that has any audio other than AC3 5.1
i have this error too. i can’t reproduce anything that is AC3 5.1 because i get the “Conversation failed” error. Same content with AAC audio play just fine. The log also show an error with eac3 codec:
May 01, 2018 17:17:19.654 [4408] ERROR - [Transcoder] [eac3_mf @ 03e45840] could not find any MFT for the given media type
May 01, 2018 17:17:19.658 [7536] ERROR - [Transcoder] [eac3_mf @ 03e45840] could not create MFT
May 01, 2018 17:17:19.670 [4408] ERROR - [Transcoder] Error while opening decoder for input stream #0:1 : Function not implemented
May 01, 2018 17:17:19.679 [10688] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\PlexTranscoder.exe' exit code for process 1596 is 1 (failure)
All this happen with latest plex pass update (1.13.0.5003). All videos was reproduced perfectly with previous version.
Please do not double post.
In any case your error is not the same as the one you did a quote post for.
The transcoder error you are referring to is a generic error with different underlying errors giving rise to it. You can clearly see your log lines that you extracted are different from the ones in earlier post
While I said that I could not test anything, I had some spare time and was able to prep some logs for you… The same errors as before:
Line 12938: May 03, 2018 17:31:14.781 [62776] ERROR - [Transcoder] av_interleaved_write_frame(): Unknown error
Line 12938: May 03, 2018 17:31:14.781 [62776] ERROR - [Transcoder] av_interleaved_write_frame(): Unknown error
Line 12939: May 03, 2018 17:31:14.791 [59340] ERROR - [Transcoder] Error writing trailer of media-%05d.ts: Invalid argument
Line 12939: May 03, 2018 17:31:14.791 [59340] ERROR - [Transcoder] Error writing trailer of media-%05d.ts: Invalid argument
Line 12966: May 03, 2018 17:31:15.029 [59116] DEBUG - DVR:Grabber: Recording for 'ABC World News Tonight With David Muir - Episode 05-03' stopped with status error.
While I said that I could not test anything, I had some spare time and was able to prep some logs for you… The same errors as before:
Line 12938: May 03, 2018 17:31:14.781 [62776] ERROR - [Transcoder] av_interleaved_write_frame(): Unknown error
Line 12938: May 03, 2018 17:31:14.781 [62776] ERROR - [Transcoder] av_interleaved_write_frame(): Unknown error
Line 12939: May 03, 2018 17:31:14.791 [59340] ERROR - [Transcoder] Error writing trailer of media-%05d.ts: Invalid argument
Line 12939: May 03, 2018 17:31:14.791 [59340] ERROR - [Transcoder] Error writing trailer of media-%05d.ts: Invalid argument
Line 12966: May 03, 2018 17:31:15.029 [59116] DEBUG - DVR:Grabber: Recording for 'ABC World News Tonight With David Muir - Episode 05-03' stopped with status error.
I have attached the logs…
Thank you - so these errors are still occurring on 1.13.0.5003
If it is easily reproducible could you try with transcoder log levels of verbose and also separate test with log level trace
Both would need the server logging to be enabled for both verbose and debug
[Settings / Server / General / Show Advanced)
Thank you - so these errors are still occurring on 1.13.0.5003
If it is easily reproducible could you try with transcoder log levels of verbose and also separate test with log level trace
Both would need the server logging to be enabled for both verbose and debug
[Settings / Server / General / Show Advanced)
Thank you - so these errors are still occurring on 1.13.0.5003
If it is easily reproducible could you try with transcoder log levels of verbose and also separate test with log level trace
Both would need the server logging to be enabled for both verbose and debug
[Settings / Server / General / Show Advanced)
'trace` may slow down the transcoder significantly and change the timing
if it does not occur with trace, try verbose
Well… Both trace and verbose yielded no errors… Just a completed file that had significant timeing issues with audio/video sync.
Removing the transcodeloglevel key from the registry, and recording another OTA recording resulted in another error almost right away… There has to something in the timing.
As an aside, my plex temp directory is set to an SSD so the timing is not (should not…) be bus/disk related.
Here are the specifics:
Line 34719: May 04, 2018 06:05:20.514 [100272] ERROR - Caught exception trying to stream file: C: emp\Plex_Temp\Transcode\Sessions\plex-transcode-f921274e-83f4-4fe1-a64f-534d30b34404\media-00104.ts: write: A request to send or receive data was disallowed because the socket had already been shut down in that direction with a previous shutdown call
Line 34765: May 04, 2018 06:05:22.365 [98508] ERROR - [Transcoder] [stream_segment,ssegment @ 051f5d00] Failed to open segment list 'http://127.0.0.1:32400/video/:/transcode/session/368306f4-0ac3-408e-9f58-95c99831975c/968fbb28-bb27-49e5-bfbf-a269f85beb3b/seglist'
Line 34766: May 04, 2018 06:05:22.381 [97316] ERROR - [Transcoder] av_interleaved_write_frame(): Unknown error
Line 34766: May 04, 2018 06:05:22.381 [97316] ERROR - [Transcoder] av_interleaved_write_frame(): Unknown error
Line 34767: May 04, 2018 06:05:22.383 [99852] ERROR - [Transcoder] Error writing trailer of media-%05d.ts: Invalid argument
Line 34767: May 04, 2018 06:05:22.383 [99852] ERROR - [Transcoder] Error writing trailer of media-%05d.ts: Invalid argument
Line 35007: May 04, 2018 06:05:22.698 [98000] DEBUG - DVR:Grabber: Recording for 'CBS 11 News This Morning at 6AM - Episode 05-04' stopped with status error.
Line 36423: May 04, 2018 06:05:38.618 [100272] ERROR - Error issuing curl_easy_perform(handle): 28
Line 36423: May 04, 2018 06:05:38.618 [100272] ERROR - Error issuing curl_easy_perform(handle): 28
Line 36433: May 04, 2018 06:05:38.631 [95568] DEBUG - DVR:Grabber: Operation for CBS 11 News This Morning at 6AM - Episode 05-04 completed with status error (There was a transcoder error)
Line 36433: May 04, 2018 06:05:38.631 [95568] DEBUG - DVR:Grabber: Operation for CBS 11 News This Morning at 6AM - Episode 05-04 completed with status error (There was a transcoder error)
Just in case you can use them, I ave attached the total logs.
'trace` may slow down the transcoder significantly and change the timing
if it does not occur with trace, try verbose
Well… Both trace and verbose yielded no errors… Just a completed file that had significant timeing issues with audio/video sync.
Removing the transcodeloglevel key from the registry, and recording another OTA recording resulted in another error almost right away… There has to something in the timing.
As an aside, my plex temp directory is set to an SSD so the timing is not (should not…) be bus/disk related.
Here are the specifics:
Line 34719: May 04, 2018 06:05:20.514 [100272] ERROR - Caught exception trying to stream file: C: emp\Plex_Temp\Transcode\Sessions\plex-transcode-f921274e-83f4-4fe1-a64f-534d30b34404\media-00104.ts: write: A request to send or receive data was disallowed because the socket had already been shut down in that direction with a previous shutdown call
Line 34765: May 04, 2018 06:05:22.365 [98508] ERROR - [Transcoder] [stream_segment,ssegment @ 051f5d00] Failed to open segment list 'http://127.0.0.1:32400/video/:/transcode/session/368306f4-0ac3-408e-9f58-95c99831975c/968fbb28-bb27-49e5-bfbf-a269f85beb3b/seglist'
Line 34766: May 04, 2018 06:05:22.381 [97316] ERROR - [Transcoder] av_interleaved_write_frame(): Unknown error
Line 34766: May 04, 2018 06:05:22.381 [97316] ERROR - [Transcoder] av_interleaved_write_frame(): Unknown error
Line 34767: May 04, 2018 06:05:22.383 [99852] ERROR - [Transcoder] Error writing trailer of media-%05d.ts: Invalid argument
Line 34767: May 04, 2018 06:05:22.383 [99852] ERROR - [Transcoder] Error writing trailer of media-%05d.ts: Invalid argument
Line 35007: May 04, 2018 06:05:22.698 [98000] DEBUG - DVR:Grabber: Recording for 'CBS 11 News This Morning at 6AM - Episode 05-04' stopped with status error.
Line 36423: May 04, 2018 06:05:38.618 [100272] ERROR - Error issuing curl_easy_perform(handle): 28
Line 36423: May 04, 2018 06:05:38.618 [100272] ERROR - Error issuing curl_easy_perform(handle): 28
Line 36433: May 04, 2018 06:05:38.631 [95568] DEBUG - DVR:Grabber: Operation for CBS 11 News This Morning at 6AM - Episode 05-04 completed with status error (There was a transcoder error)
Line 36433: May 04, 2018 06:05:38.631 [95568] DEBUG - DVR:Grabber: Operation for CBS 11 News This Morning at 6AM - Episode 05-04 completed with status error (There was a transcoder error)
Just in case you can use them, I ave attached the total logs.
Thank you for trying. I do believe it is a timing issue affecting a number of users
Today was the first time I ever had this issue with the beta of the server and windows player (as of 12/May/18). I disabled the setting in the media server where Plex will play a trailer before the main movie and for me the error went away.
Thank you for trying. I do believe it is a timing issue affecting a number of users
Just checking…
Using the newly release update- May 26, 2018 08:56:49.211 [14140] INFO - Plex Media Server v1.13.2.5102-4898d7d96 - Microsoft PC x64 - build: windows-i386 english - GMT -05:00
I get repeated errors and am unable to record ANYTHING…
Line 295: May 26, 2018 11:35:58.459 [23200] ERROR - [Transcoder] av_interleaved_write_frame(): Unknown error
Line 296: May 26, 2018 11:35:58.461 [18044] ERROR - [Transcoder] Error writing trailer of media-%05d.ts: Invalid argument
Thank you for trying. I do believe it is a timing issue affecting a number of users
Just checking…
Using the newly release update- May 26, 2018 08:56:49.211 [14140] INFO - Plex Media Server v1.13.2.5102-4898d7d96 - Microsoft PC x64 - build: windows-i386 english - GMT -05:00
I get repeated errors and am unable to record ANYTHING…
Line 295: May 26, 2018 11:35:58.459 [23200] ERROR - [Transcoder] av_interleaved_write_frame(): Unknown error
Line 296: May 26, 2018 11:35:58.461 [18044] ERROR - [Transcoder] Error writing trailer of media-%05d.ts: Invalid argument
Lets have debug logging enabled
restart the server
reproduce the problem
capture the server logs zip
and attach the zip file
Lets have debug logging enabled
restart the server
reproduce the problem
capture the server logs zip
and attach the zip file
Done…
Line 10085: May 26, 2018 16:32:02.389 [30284] ERROR - [Transcoder] av_interleaved_write_frame(): Unknown error
Line 10085: May 26, 2018 16:32:02.389 [30284] ERROR - [Transcoder] av_interleaved_write_frame(): Unknown error
Line 10086: May 26, 2018 16:32:02.395 [36340] ERROR - [Transcoder] Error writing trailer of media-%05d.ts: Invalid argument
Line 10086: May 26, 2018 16:32:02.395 [36340] ERROR - [Transcoder] Error writing trailer of media-%05d.ts: Invalid argument
Line 10109: May 26, 2018 16:32:03.374 [27320] DEBUG - DVR:Grabber: Recording for 'PGA Tour Golf - Fort Worth Invitational, Third Round' stopped with status error.
Line 10287: May 26, 2018 16:33:08.684 [27320] DEBUG - Job running: EAE_ROOT='\\?\C: emp\Plex_Temp\Transcode\Sessions\EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='C\:\\Users\\Jason\\AppData\\Local\\Plex\ Media\ Server\\Codecs\\4b51335-1293-windows-i386\\' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' C:\Program Files (x86)\Plex\Plex Media Server\PlexTranscoder.exe -y -safe 0 -skip_before_video_key 1 -skip_before_inpoint 1 -offset_inout 1 -i Y:\Videos\TV\.grab\2b18550452ddc43cfb003dba93eb047f3938560d\segment-list.txt -map 0 -c copy -loglevel quiet -loglevel_plex error "Y:\Videos\TV\.grab\2b18550452ddc43cfb003dba93eb047f3938560d\PGA Tour Golf (2003) - 2018-05-26 14 00 00 - Fort Worth Invitational Third Round-comskipped.ts"
Line 10417: May 26, 2018 16:33:19.438 [35028] ERROR - Error response for update: 5 (Unhandled exception).
Line 10417: May 26, 2018 16:33:19.438 [35028] ERROR - Error response for update: 5 (Unhandled exception).
Line 10418: May 26, 2018 16:33:19.440 [35028] DEBUG - DVR:Grabber: Operation for PGA Tour Golf - Fort Worth Invitational, Third Round completed with status error (There was a transcoder error)
Line 10418: May 26, 2018 16:33:19.440 [35028] DEBUG - DVR:Grabber: Operation for PGA Tour Golf - Fort Worth Invitational, Third Round completed with status error (There was a transcoder error)
Lets have debug logging enabled
restart the server
reproduce the problem
capture the server logs zip
and attach the zip file
… Just checking. DId this secondary round of logs help out in determining anything o n this issue?
Sorry did not pick it earlier. I have passed it to the development team
If transcoder verbose logging reduces the likelihood of getting the error you could run with that - just leave server logging to debug (no verbose) and see if makes a difference
If transcoder verbose logging reduces the likelihood of getting the error you could run with that - just leave server logging to debug (no verbose) and see if makes a difference
Verbose or otherwise… 99.9% of all recording end in an error…
Snippet…
Line 12884: May 31, 2018 05:52:23.936 [74956] ERROR - Caught exception trying to stream file: C: emp\Plex_Temp\Transcode\Sessions\plex-transcode-5d2a3b63-a262-45c4-87d9-55f307ab0163\media-00065.ts: write: A request to send or receive data was disallowed because the socket had already been shut down in that direction with a previous shutdown call
Line 13121: May 31, 2018 05:52:31.454 [75108] ERROR - [Transcoder] [stream_segment,ssegment @ 04a3bc80] Failed to open segment list 'http://127.0.0.1:32400/video/:/transcode/session/5d2a3b63-a262-45c4-87d9-55f307ab0163/92c1c6f2-9fb2-4fc4-9f54-706be4d640ce/seglist'
Line 13122: May 31, 2018 05:52:31.460 [74956] ERROR - [Transcoder] av_interleaved_write_frame(): Unknown error
Line 13122: May 31, 2018 05:52:31.460 [74956] ERROR - [Transcoder] av_interleaved_write_frame(): Unknown error
Line 13123: May 31, 2018 05:52:31.463 [75108] ERROR - [Transcoder] Error writing trailer of media-%05d.ts: Invalid argument
Line 13123: May 31, 2018 05:52:31.463 [75108] ERROR - [Transcoder] Error writing trailer of media-%05d.ts: Invalid argument
Line 13307: May 31, 2018 05:52:32.303 [75368] DEBUG - DVR:Grabber: Recording for 'Good Day at 5AM - Episode 05-31' stopped with status error.
Line 13545: May 31, 2018 05:52:35.934 [75616] ERROR - Error issuing curl_easy_perform(handle): 7
Line 13545: May 31, 2018 05:52:35.934 [75616] ERROR - Error issuing curl_easy_perform(handle): 7
Line 13546: May 31, 2018 05:52:35.934 [75616] WARN - HTTP error requesting GET http://127.0.0.1:3381/:/events/systemBundleRestarted (0, No error) (Failed connect to 127.0.0.1:3381; No error)
Line 13546: May 31, 2018 05:52:35.934 [75616] WARN - HTTP error requesting GET http://127.0.0.1:3381/:/events/systemBundleRestarted (0, No error) (Failed connect to 127.0.0.1:3381; No error)
Line 13546: May 31, 2018 05:52:35.934 [75616] WARN - HTTP error requesting GET http://127.0.0.1:3381/:/events/systemBundleRestarted (0, No error) (Failed connect to 127.0.0.1:3381; No error)
Line 13869: May 31, 2018 05:52:53.480 [75512] WARN - LongPoll: Got error, closing.
Line 13941: May 31, 2018 05:53:06.772 [66828] DEBUG - DVR:Grabber: Operation for Good Day at 5AM - Episode 05-31 completed with status error (There was a transcoder error)
Line 13941: May 31, 2018 05:53:06.772 [66828] DEBUG - DVR:Grabber: Operation for Good Day at 5AM - Episode 05-31 completed with status error (There was a transcoder error)
@sa2000 said:
I suggested that because you indicated before that
Well… Both trace and verbose yielded no errors…
Gotcha… I re-added the verbose reg key and came up with more info… Full logs attached as well.
Snippet…
May 31, 2018 07:56:47.796 [25752] ERROR - [Transcoder] [stream_segment,ssegment @ 046fbe00] Failed to open segment list 'http://127.0.0.1:32400/video/:/transcode/session/ebc51ca3-3b02-4d5c-aa9d-65c70b8cd7a0/6cd537cc-9115-45ed-a566-5602818683a4/seglist'
May 31, 2018 07:56:47.810 [24324] ERROR - [Transcoder] av_interleaved_write_frame(): Unknown error
May 31, 2018 07:56:47.833 [24324] ERROR - [Transcoder] Error writing trailer of media-%05d.ts: Invalid argument
May 31, 2018 07:56:47.847 [24324] INFO - [Transcoder] frame=34197 fps= 30 q=-1.0 Lsize=N/A time=00:19:01.28 bitrate=N/A speed= 1x
May 31, 2018 07:56:47.849 [25752] INFO - [Transcoder] video:422595kB audio:53480kB subtitle:0kB other streams:0kB global headers:0kB muxing overhead: unknown
May 31, 2018 07:56:47.876 [25752] INFO - [Transcoder] Conversion failed!
May 31, 2018 07:56:52.681 [27436] INFO - [Transcoder] frame=34124 fps= 30 q=-1.0 Lsize= 519862kB time=00:18:58.84 bitrate=3739.5kbits/s speed=0.998x
May 31, 2018 07:56:52.696 [25752] INFO - [Transcoder] video:421064kB audio:53384kB subtitle:0kB other streams:0kB global headers:0kB muxing overhead: 9.571904%
May 31, 2018 07:56:52.922 [7880] WARN - [FFMPEG] - ignoring invalid SAR: 3/4
May 31, 2018 07:56:52.937 [26992] INFO - Library section 5 (TV Shows (DVR)) will be updated because of a change in Y:\Videos\TV (DVR)\Today (1952)
May 31, 2018 07:56:52.941 [12172] INFO - Library section 5 (TV Shows (DVR)) will be updated because of a change in Y:\Videos\TV (DVR)\Today (1952)\Season 2018
General
ID : 1 (0x1)
Complete name : Y:\Videos\TV (DVR)\Today (1952)\Season 2018\Today (1952) - 2018-05-31 07 00 00 - Episode 05-31.ts
Format : MPEG-TS
File size : 508 MiB
Duration : 18 min 56 s
Overall bit rate mode : Variable
Overall bit rate : 3 733 kb/s
Video
ID : 256 (0x100)
Menu ID : 1 (0x1)
Format : AVC
Format/Info : Advanced Video Codec
Format profile : High@L4.1
Format settings : CABAC / 2 Ref Frames
Format settings, CABAC : Yes
Format settings, ReFrames : 2 frames
Codec ID : 27
Duration : 18 min 58 s
Bit rate mode : Variable
Bit rate : 3 160 kb/s
Maximum bit rate : 12.0 Mb/s
Width : 1 280 pixels
Height : 540 pixels
Display aspect ratio : 16:9
Frame rate : 29.970 (30000/1001) FPS
Standard : Component
Color space : YUV
Chroma subsampling : 4:2:0
Bit depth : 8 bits
Scan type : Progressive
Bits/(Pixel*Frame) : 0.153
Stream size : 429 MiB (84%)
Color range : Limited
Audio
ID : 257 (0x101)
Menu ID : 1 (0x1)
Format : AC-3
Format/Info : Audio Coding 3
Codec ID : 129
Duration : 18 min 58 s
Bit rate mode : Constant
Bit rate : 384 kb/s
Channel(s) : 6 channels
Channel positions : Front: L C R, Side: L R, LFE
Sampling rate : 48.0 kHz
Frame rate : 31.250 FPS (1536 SPF)
Bit depth : 16 bits
Compression mode : Lossy
Delay relative to video : -307 ms
Stream size : 52.1 MiB (10%)
Language : English
Service kind : Complete Main
Text #1
ID : 256 (0x100)-CC1
Menu ID : 1 (0x1)
Format : EIA-608
Muxing mode : SCTE 128 / DTVCC Transport
Muxing mode, more info : Muxed in Video #1
Duration : 18 min 58 s
Bit rate mode : Constant
Stream size : 0.00 Byte (0%)
CaptionServiceName : CC1
Text #2
ID : 256 (0x100)-1
Menu ID : 1 (0x1)
Format : EIA-708
Muxing mode : SCTE 128 / DTVCC Transport
Muxing mode, more info : Muxed in Video #1
Duration : 18 min 58 s
Bit rate mode : Constant
Stream size : 0.00 Byte (0%)
Menu
ID : 4096 (0x1000)
Menu ID : 1 (0x1)
Duration : 18 min 56 s
List : 256 (0x100) (AVC) / 257 (0x101) (AC-3, English)
Language : / English
Service name : Service01
Service provider : FFmpeg
Service type : digital television
