Conversion failed playback error when subtitles are over (usually at the end)

Plex Players Smart TVs
, , , , ,
1

While watching a movie with subtitles, the playback stops unexpectedly (usually at the end of content according to many similar reports). The following error is displayed:

Playback error
Conversion failed. The transcoder exited due to an error.

After resuming the playback, the movie stops again after a while. And again, and again, …

Environment:
Server Version#: latest - 1.32.2.7100 (linux/debian 11)
Player Version#: latest - 5.61.0 (LG C9)
Movie: MKV with embedded SRT subtitles.

Findings:

  • The problem has something to do with subtitles. When subtitles are off, the problem never appeared.
  • The problems occur only when subtitles are over, i.e. after the last subtitle occurrence. In this case, the last record in the subtitles was around 1h 45m (of 3h 15m movie). The playback never stopped before 1h 45m when subtitles were available. (Subtitles were “forced”, so only a few subtitles are included).
  • I’m able to reproduce it on demand, I’m able to reproduce with overlay and logging to PMS enabled. Logs available on demand, if needed. Screenshot attached.
  • Cannot reproduce on other Plex Players - Windows app, Mobile/Android app.

The most important errors/warns from the logs:

ERROR [Subtitles: Connection Monitor] An unknown error ocurred: 
WARN  [Player] A critical HTML media error occurred: 2 (Network); the player cannot recover
WARN  [PlaybackSessionController] could not retry, reporting error
ERROR [Subtitles: Connection Monitor] An unknown error ocurred: <html><head><title>Bad Request</title></head><body><h1>400 Bad Request</h1></body></html>

20230603_232900~2
20230603_232900~21016×492 147 KB

3 Likes
2

This bug seems to have been present for a month or more. Also on other platforms (Samsung) though doesn’t seem to be an issue on Android so I assume it is not server related but more likely app related.

The only issue for me is that movie and TV files stop with this error as soon as the last subtitle has been shown which is obviously anything from seconds to minutes before the end of the file. It is consistent every time. SRT subtitles in this case

When it happens the following entries show up:

Jun 14, 2023 22:53:10.229 [140452660251448] Warning — [Req#347c9] Denying access to direct play of part 854492 due to terminated session
Jun 14, 2023 22:53:10.248 [140452742277944] Error — [TranscodeOutputStream] Session appears to have died from under us
Jun 14, 2023 22:53:10.294 [140452666460984] Error — [Req#347b7] Unable to find client profile for device; platform=webOS, platformVersion=3.4.0, device=webOS 3.4.0, model=OLED55C6V-Z
Jun 14, 2023 22:53:10.721 [140452666460984] Error — [Req#347e9] Unable to find client profile for device; platform=webOS, platformVersion=3.4.0, device=webOS 3.4.0, model=OLED55C6V-Z
Jun 14, 2023 22:53:13.400 [140452666460984] Warning — [Req#347fc/Transcode] Denying access due to session lacking permission to transcode key /library/metadata/571631
Jun 14, 2023 22:53:16.513 [140452660251448] Warning — [Req#34801/Transcode] Denying access due to session lacking permission to transcode key /library/metadata/571631
Jun 14, 2023 22:53:19.628 [140452666460984] Warning — [Req#3480a/Transcode] Denying access due to session lacking permission to transcode key /library/metadata/571631

Any help would be much appreciated? @markus101

1 Like
3

Thanks for the info, have a few follow-up things to make sure I can file an appropriate issue.

  • Please enable logging to PMS and reproduce the issue and upload the logs
  • Does it only occur with an MKV that has embedded subtitles or do external subtitles trigger it for you as well?
  • Are you able to reproduce this with a shorter video or does it only happen with longer videos where the subtitles end part way through?
  • If reproducible with a shorter video can you provide a sample please?
4

@markus101,@sLumpy I think you can merge all of these posts, they’re all related.

Unexpected Playback error at end of TV episodes - Plex Players / Smart TVs - Plex Forum

Unexpected Playback error at end of movies/episodes - Plex Players / Smart TVs - Plex Forum

Transcode Errors (Samsung Tizen) - Plex Players / Smart TVs - Plex Forum

And probably others.

I could reproduce this on the Smart TV app, but not on Plex HTPC or Plex Web.

From my test, It only happens when:

Matroska is the container.
Direct Play is enabled.
Text Subtitles are enabled (.srt or .ass) (AppRenders shows the Video Information on the player)
Always happen near the end of the video.
Video may have subtitles, but if they’re not enabled the error will not appear.
It doesn’t happen on mp4 containers (incl. subtitles) from my tests.

You can reproduce yourselves with any Matroska with subtitles enabled, on a SmartTV Player. (It’ll give you the mentioned error)

Correction on above, I did find some Matroskas with subtitles that do not show this behaviour. But there were a few, I’m unable to identify why happens in some and not in others. Will check if
the length of the subtitles has something to do with it.
Also, I was able to reproduce long and short videos.
Not tested with external subtitles.

Some Logs and Tidbits

  • Directplay is not applied on the server, but transcoding. The client shows directplay as the source and matroska, HTTP, in Information, but the server is actually transcoding…
  • Client setup, Audio: AC3, and EAC3, enabled, DTs and MultiAudio normalization disabled. Video: Local Quality: Original, Allow Direct Play, Allow Direct Steam, Maximum H.264 5.2. Subtitles: Burn Never.

Client Error:

image
image1920×372 125 KB

Server Log At The Time

Jun 16, 2023 23:47:39.867 [9468] DEBUG - Request: [127.0.0.1:61869 (Loopback)] PUT /video/:/transcode/session/p6uolhfmeyme3c6xawj8adsk/a1ec70e6-2eef-4502-9cd4-e9f272939146/progress?status=opening (6 live) #582a3 Signed-in Token (mpiva) (range: bytes=0-) 
Jun 16, 2023 23:47:39.867 [20400] DEBUG - Completed: [127.0.0.1:61869] 204 PUT /video/:/transcode/session/p6uolhfmeyme3c6xawj8adsk/a1ec70e6-2eef-4502-9cd4-e9f272939146/progress?status=opening (6 live) #582a3 0ms 203 bytes (pipelined: 3) (range: bytes=0-) 
Jun 16, 2023 23:47:39.874 [9468] DEBUG - Request: [127.0.0.1:61869 (Loopback)] PUT /video/:/transcode/session/p6uolhfmeyme3c6xawj8adsk/a1ec70e6-2eef-4502-9cd4-e9f272939146/progress?status=opened (6 live) #582a4 Signed-in Token (mpiva) (range: bytes=0-) 
Jun 16, 2023 23:47:39.874 [20400] DEBUG - Completed: [127.0.0.1:61869] 204 PUT /video/:/transcode/session/p6uolhfmeyme3c6xawj8adsk/a1ec70e6-2eef-4502-9cd4-e9f272939146/progress?status=opened (6 live) #582a4 0ms 203 bytes (pipelined: 4) (range: bytes=0-) 
Jun 16, 2023 23:47:39.874 [9468] DEBUG - Request: [127.0.0.1:61869 (Loopback)] PUT /video/:/transcode/session/p6uolhfmeyme3c6xawj8adsk/a1ec70e6-2eef-4502-9cd4-e9f272939146/progress/stream?index=1&id=0&codec=h264&type=video (6 live) #582a5 Signed-in Token (mpiva) (range: bytes=0-) 
Jun 16, 2023 23:47:39.875 [20400] DEBUG - Completed: [127.0.0.1:61869] 200 PUT /video/:/transcode/session/p6uolhfmeyme3c6xawj8adsk/a1ec70e6-2eef-4502-9cd4-e9f272939146/progress/stream?index=1&id=0&codec=h264&type=video (6 live) #582a5 0ms 195 bytes (pipelined: 5) (range: bytes=0-) 
Jun 16, 2023 23:47:39.928 [9468] DEBUG - Request: [127.0.0.1:61869 (Loopback)] PUT /video/:/transcode/session/p6uolhfmeyme3c6xawj8adsk/a1ec70e6-2eef-4502-9cd4-e9f272939146/progress/stream?index=0&id=0&codec=aac&type=audio&profile=LC (6 live) #582a6 Signed-in Token (mpiva) (range: bytes=0-) 
Jun 16, 2023 23:47:39.929 [20400] DEBUG - Completed: [127.0.0.1:61869] 200 PUT /video/:/transcode/session/p6uolhfmeyme3c6xawj8adsk/a1ec70e6-2eef-4502-9cd4-e9f272939146/progress/stream?index=0&id=0&codec=aac&type=audio&profile=LC (6 live) #582a6 0ms 195 bytes (pipelined: 6) (range: bytes=0-) 
Jun 16, 2023 23:47:39.929 [9468] DEBUG - Request: [127.0.0.1:61869 (Loopback)] PUT /video/:/transcode/session/p6uolhfmeyme3c6xawj8adsk/a1ec70e6-2eef-4502-9cd4-e9f272939146/progress/streamDetail?index=0&id=0&codec=aac&type=audio&profile=LC&channels=2&layout=stereo&sampleRate=48000&disp_default=1 (6 live) #582a7 Signed-in Token (mpiva) (range: bytes=0-) 
Jun 16, 2023 23:47:39.929 [20400] DEBUG - Completed: [127.0.0.1:61869] 200 PUT /video/:/transcode/session/p6uolhfmeyme3c6xawj8adsk/a1ec70e6-2eef-4502-9cd4-e9f272939146/progress/streamDetail?index=0&id=0&codec=aac&type=audio&profile=LC&channels=2&layout=stereo&sampleRate=48000&disp_default=1 (6 live) #582a7 0ms 195 bytes (pipelined: 7) (range: bytes=0-) 
Jun 16, 2023 23:47:39.929 [9468] DEBUG - Request: [127.0.0.1:61869 (Loopback)] PUT /video/:/transcode/session/p6uolhfmeyme3c6xawj8adsk/a1ec70e6-2eef-4502-9cd4-e9f272939146/progress/streamDetail?index=1&id=0&codec=h264&type=video&profile=High&language=jpn&width=1920&height=1080&interlaced=0&sar=1:1&level=41&frameRate=29.970&disp_default=1 (6 live) #582a8 Signed-in Token (mpiva) (range: bytes=0-) 
Jun 16, 2023 23:47:39.930 [20400] DEBUG - Completed: [127.0.0.1:61869] 200 PUT /video/:/transcode/session/p6uolhfmeyme3c6xawj8adsk/a1ec70e6-2eef-4502-9cd4-e9f272939146/progress/streamDetail?index=1&id=0&codec=h264&type=video&profile=High&language=jpn&width=1920&height=1080&interlaced=0&sar=1:1&level=41&frameRate=29.970&disp_default=1 (6 live) #582a8 0ms 195 bytes (pipelined: 8) (range: bytes=0-) 
Jun 16, 2023 23:47:39.930 [9468] DEBUG - Request: [127.0.0.1:61869 (Loopback)] PUT /video/:/transcode/session/p6uolhfmeyme3c6xawj8adsk/a1ec70e6-2eef-4502-9cd4-e9f272939146/progress/streamDetail?index=2&id=0&codec=ass&type=subtitle&language=eng&disp_default=1&disp_forced=1 (6 live) #582a9 Signed-in Token (mpiva) (range: bytes=0-) 
Jun 16, 2023 23:47:39.930 [20400] DEBUG - Completed: [127.0.0.1:61869] 200 PUT /video/:/transcode/session/p6uolhfmeyme3c6xawj8adsk/a1ec70e6-2eef-4502-9cd4-e9f272939146/progress/streamDetail?index=2&id=0&codec=ass&type=subtitle&language=eng&disp_default=1&disp_forced=1 (6 live) #582a9 0ms 195 bytes (pipelined: 9) (range: bytes=0-) 
Jun 16, 2023 23:47:39.932 [9468] DEBUG - Request: [127.0.0.1:61869 (Loopback)] PUT /video/:/transcode/session/p6uolhfmeyme3c6xawj8adsk/a1ec70e6-2eef-4502-9cd4-e9f272939146/progress?duration=136.213000 (6 live) #582aa Signed-in Token (mpiva) (range: bytes=0-) 
Jun 16, 2023 23:47:39.932 [11116] DEBUG - [Req#58293/Transcode] Started session successfully: p6uolhfmeyme3c6xawj8adsk
Jun 16, 2023 23:47:39.932 [12276] DEBUG - Completed: [127.0.0.1:61869] 204 PUT /video/:/transcode/session/p6uolhfmeyme3c6xawj8adsk/a1ec70e6-2eef-4502-9cd4-e9f272939146/progress?duration=136.213000 (6 live) #582aa 0ms 203 bytes (pipelined: 10) (range: bytes=0-) 
Jun 16, 2023 23:47:39.932 [11116] DEBUG - [Req#58293/Transcode] Cleaning directory for session p6uolhfmeyme3c6xawj8adsk (C:\Users\mpiva\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-p6uolhfmeyme3c6xawj8adsk-2f58a428-123f-49f0-a122-91fd42236a3a)
Jun 16, 2023 23:47:39.934 [11116] DEBUG - Content-Length is -1 (of total: -1).
Jun 16, 2023 23:47:39.935 [5672] DEBUG - [TranscodeOutputStream] Input processing thread started at offset 0 for -1 bytes.
Jun 16, 2023 23:47:41.006 [19600] DEBUG - Jobs: 'C:\Program Files\Plex\Plex Media Server\Plex Transcoder.exe' exit code for process 16908 is -1073741819 ()
Jun 16, 2023 23:47:41.006 [15308] DEBUG - Notifying consumer that data is complete.
Jun 16, 2023 23:47:41.006 [5672] DEBUG - [TranscodeOutputStream] Input processing thread exited after writing 0 bytes, m_closed=0, m_endOfFileReached=1, session->isStopped()=0
Jun 16, 2023 23:47:41.006 [20812] DEBUG - Killing job.
Jun 16, 2023 23:47:41.006 [20812] DEBUG - Job was already killed, not killing again.
Jun 16, 2023 23:47:41.006 [20812] DEBUG - Stopping transcode session p6uolhfmeyme3c6xawj8adsk
Jun 16, 2023 23:47:41.006 [15308] DEBUG - Streaming Resource: Terminated session 0000018B32C50A10:lf6kcae8yzn5o1ef8tyfiewd with reason Conversion failed. The transcoder process crashed.
Jun 16, 2023 23:47:41.006 [15308] DEBUG - Cleaning directory for session p6uolhfmeyme3c6xawj8adsk (C:\Users\mpiva\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-p6uolhfmeyme3c6xawj8adsk-a1ec70e6-2eef-4502-9cd4-e9f272939146)
Jun 16, 2023 23:47:41.017 [20812] DEBUG - Transcoder: Cleaning old transcode directories.
Jun 16, 2023 23:47:41.019 [20812] DEBUG - Whacked session p6uolhfmeyme3c6xawj8adsk, 0 remaining.
Jun 16, 2023 23:47:41.019 [20812] DEBUG - Cleaning directory for session p6uolhfmeyme3c6xawj8adsk (C:\Users\mpiva\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-p6uolhfmeyme3c6xawj8adsk-a1ec70e6-2eef-4502-9cd4-e9f272939146)
Jun 16, 2023 23:47:41.024 [12276] ERROR - [TranscodeOutputStream] Session appears to have died from under us

Initial Assessment: Plex Transcoder crashed.

Last statistics from Plex Transcoder

<SessionReport version="2" startTime="0" startTimestamp="Jun 16, 2023 23:47:39.741" key="/library/metadata/437026" session="lf6kcae8yzn5o1ef8tyfiewd" transcode="p6uolhfmeyme3c6xawj8adsk">
<User id="1" thumb="https://plex.tv/users/7d9db1c60ea50721/avatar?c=1630931305" title="mpiva" />
<Player address="192.168.5.223" device="webOS 6.3.2" machineIdentifier="lf6kcae8yzn5o1ef8tyfiewd" model="OLED65G1PUA" platform="webOS" platformVersion="6.3.2" product="Plex for LG" remotePublicAddress="179.26.230.92" title="LG OLED65G1PUA" vendor="LG" version="5.62.0" local="1" relayed="0" secure="0" userID="1" />
<Variants>
<Variant id="a1ec70e6-2eef-4502-9cd4-e9f272939146" targetBitrate="3283" context="streaming" subtitleDecision="copy" protocol="http" container="ass" transcodeHwRequested="0" transcodeHwFullPipeline="0">
<Media audioProfile="lc" id="543231" videoProfile="high" container="ass" duration="136213" selected="1">
<Part audioProfile="lc" id="552076" videoProfile="high" container="ass" duration="136213" decision="transcode" selected="1">
<Stream codec="ass" default="1" displayTitle="English Forced (ASS)" extendedDisplayTitle="English (Forced ASS)" forced="1" format="ass" id="1887849" language="English" languageCode="eng" languageTag="en" selected="1" streamType="3" title="English" decision="copy" location="embedded" />
</Part>
</Media>
</Variant>
</Variants>
<SegmentList />
<PlaybackReports />
<StateReports />
<StateReports />
</SessionReport>

Server transcoding decision IDK why.

Jun 16, 2023 23:43:37.927 [4944] DEBUG - [Req#57edf/Transcode] TranscodeUniversalRequest: adapting profile with augmentation data: add-transcode-target(type=videoProfile&context=streaming&protocol=hls&container=mpegts&videoCodec=h264,hevc,mpeg2video,mpeg4&audioCodec=aac,ac3,eac3,mp2,mp3)+add-limitation(scope=videoAudioCodec&scopeName=mp2&type=upperBound&name=audio.bitrate&value=350)+add-limitation(scope=videoAudioCodec&scopeName=mp3&type=upperBound&name=audio.bitrate&value=350)+add-limitation(scope=videoCodec&scopeName=h264&type=upperBound&name=video.level&value=52)+add-limitation(scope=videoTranscodeTarget&scopeName=h264&scopeType=videoCodec&context=streaming&protocol=hls&type=upperBound&name=video.bitDepth&value=8)+add-limitation(scope=videoTranscodeTarget&scopeName=h264&scopeType=videoCodec&context=streaming&protocol=hls&type=upperBound&name=video.width&value=3840)+add-limitation(scope=videoTranscodeTarget&scopeName=h264&scopeType=videoCodec&context=streaming&protocol=hls&type=upperBound&name=video.height&value=2160)+add-limitation(scope=videoTranscodeTarget&scopeName=hevc&scopeType=videoCodec&context=streaming&protocol=hls&type=upperBound&name=video.bitDepth&value=10)+add-limitation(scope=videoTranscodeTarget&scopeName=hevc&scopeType=videoCodec&context=streaming&protocol=hls&type=upperBound&name=video.width&value=3840)+add-limitation(scope=videoTranscodeTarget&scopeName=hevc&scopeType=videoCodec&context=streaming&protocol=hls&type=upperBound&name=video.height&value=2160)+add-limitation(scope=videoTranscodeTarget&scopeName=mpeg2video&scopeType=videoCodec&context=streaming&protocol=hls&type=upperBound&name=video.bitDepth&value=8)+add-limitation(scope=videoTranscodeTarget&scopeName=mpeg2video&scopeType=videoCodec&context=streaming&protocol=hls&type=upperBound&name=video.width&value=1920)+add-limitation(scope=videoTranscodeTarget&scopeName=mpeg2video&scopeType=videoCodec&context=streaming&protocol=hls&type=upperBound&name=video.height&value=1080)+add-limitation(scope=videoTranscodeTarget&scopeName=mpeg4&scopeType=videoCodec&context=streaming&protocol=hls&type=upperBound&name=video.bitDepth&value=8)+add-limitation(scope=videoTranscodeTarget&scopeName=mpeg4&scopeType=videoCodec&context=streaming&protocol=hls&type=upperBound&name=video.width&value=1920)+add-limitation(scope=videoTranscodeTarget&scopeName=mpeg4&scopeType=videoCodec&context=streaming&protocol=hls&type=upperBound&name=video.height&value=1080)+add-transcode-target(type=subtitleProfile&protocol=http&context=all&subtitleCodec=ass&container=ass)
Jun 16, 2023 23:43:37.928 [4944] DEBUG - [Req#57edf/Transcode] TranscodeUniversalRequest: using augmented profile Generic
Jun 16, 2023 23:43:37.928 [4944] DEBUG - [Req#57edf/Transcode] Downloading document http://127.0.0.1:32400/library/metadata/437026?includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=lf6kcae8yzn5o1ef8tyfiewd
Jun 16, 2023 23:43:37.936 [4944] DEBUG - [Req#57edf/Transcode/Req#57ee0] We're going to try to auto-select an audio stream for account 1.
Jun 16, 2023 23:43:37.936 [4944] DEBUG - [Req#57edf/Transcode/Req#57ee0] Selecting best audio stream for part ID 552076 (language: es)
Jun 16, 2023 23:43:37.936 [4944] DEBUG - [Req#57edf/Transcode/Req#57ee0] Audio Stream: 1887847, Subtitle Stream: 1887849
Jun 16, 2023 23:43:37.938 [4944] DEBUG - [Req#57edf/Transcode] MDE: Selected protocol http; container: ass
Jun 16, 2023 23:43:37.938 [4944] DEBUG - [Req#57edf/Transcode] MDE: analyzing media item 543231
Jun 16, 2023 23:43:37.938 [4944] DEBUG - [Req#57edf/Transcode] MDE: [SubsPlease] Chiikawa - 44 (1080p) [71C5C7A5] (2023): no direct play video profile exists for http/mkv/h264
Jun 16, 2023 23:43:37.938 [4944] DEBUG - [Req#57edf/Transcode] MDE: [SubsPlease] Chiikawa - 44 (1080p) [71C5C7A5] (2023): no direct play video profile exists for http/mkv/h264/aac
Jun 16, 2023 23:43:37.938 [4944] DEBUG - [Req#57edf/Transcode] MDE: [SubsPlease] Chiikawa - 44 (1080p) [71C5C7A5] (2023): transcoding selected subtitle stream
Jun 16, 2023 23:43:37.938 [4944] DEBUG - [Req#57edf/Transcode] MDE: [SubsPlease] Chiikawa - 44 (1080p) [71C5C7A5] (2023): selected media 0 / 543231
Jun 16, 2023 23:43:37.938 [4944] DEBUG - [Req#57edf/Transcode] Streaming Resource: Added session 0000018B3F62C210:lf6kcae8yzn5o1ef8tyfiewd
Jun 16, 2023 23:43:37.938 [4944] DEBUG - [Req#57edf/Transcode] Streaming Resource: Reached Decision id=437026 codes=(MDE=1000,Direct play OK.) media=(id=543231 part=(id=552076 decision=direct play protocol=hls streams=(Video=(id=1887848 decision= width=1920 height=1080) Audio=(id=1887847 decision= channels=0 rate=0) Subtitle=(id=1887849 decision=copy languageCode=eng location=sidecar))))
Jun 16, 2023 23:43:37.939 [12276] DEBUG - Completed: [192.168.5.223:33304] 200 GET /video/:/transcode/universal/decision?directPlay=1&directStream=1&directStreamAudio=1&protocol=hls&fastSeek=1&path=%2Flibrary%2Fmetadata%2F437026&session=5hdlczviwv8szks491prok6v&mediaIndex=0&partIndex=0&mediaBufferSize=50000&hasMDE=1&subtitleSize=100&videoQuality=100&videoResolution=3840x2160&audioBoost=700&subtitles=sidecar&advancedSubtitles=text&location=lan (9 live) #57edf GZIP 12ms 1662 bytes (pipelined: 28)
Jun 16, 2023 23:43:38.043 [4944] DEBUG - Request: [192.168.5.223:33304 (Allowed Network (Subnet))] GET /:/timeline?key=%2Flibrary%2Fmetadata%2F437026&ratingKey=437026&state=buffering&time=0&duration=136213&playbackTime=0&playQueueItemID=177453&context=source%3Ahub.video.recent&row=1&col=14 (9 live) #57ee2 GZIP Signed-in Token (mpiva) (LG OLED65G1PUA)

@markus101 if you want a video, I could PM you or you can use google on the above. is 25Mb.

1 Like
5

Additional interesting information, maybe not related but showing flaws in the logic: when to transcode, or incorrect assumption of the client settings.

Tested the same file, but with .srt embedded instead .ass

Jun 17, 2023 00:58:20.753 [16488] DEBUG - [Req#588d1/Transcode] TranscodeUniversalRequest: using augmented profile Generic
Jun 17, 2023 00:58:20.753 [16488] DEBUG - [Req#588d1/Transcode] Downloading document http://127.0.0.1:32400/library/metadata/437061?includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=lf6kcae8yzn5o1ef8tyfiewd
Jun 17, 2023 00:58:20.762 [16488] DEBUG - [Req#588d1/Transcode/Req#588d2] We're going to try to auto-select an audio stream for account 1.
Jun 17, 2023 00:58:20.762 [16488] DEBUG - [Req#588d1/Transcode/Req#588d2] Selecting best audio stream for part ID 552125 (language: es)
Jun 17, 2023 00:58:20.762 [16488] DEBUG - [Req#588d1/Transcode/Req#588d2] We're going to try to auto-select a subtitle.
Jun 17, 2023 00:58:20.762 [16488] DEBUG - [Req#588d1/Transcode/Req#588d2] Selected a foreign audio track: 
Jun 17, 2023 00:58:20.762 [16488] DEBUG - [Req#588d1/Transcode/Req#588d2] Audio Stream: 1888193, Subtitle Stream: 1888194
Jun 17, 2023 00:58:20.764 [16488] DEBUG - [Req#588d1/Transcode] MDE: Selected protocol http; container: srt
Jun 17, 2023 00:58:20.764 [16488] DEBUG - [Req#588d1/Transcode] MDE: analyzing media item 543280
Jun 17, 2023 00:58:20.764 [16488] DEBUG - [Req#588d1/Transcode] MDE: [SubsPlease] Chiikawa - 44 (1080p) [71C5C7A5] (2) (2023): no direct play video profile exists for http/mkv/h264
Jun 17, 2023 00:58:20.764 [16488] DEBUG - [Req#588d1/Transcode] MDE: [SubsPlease] Chiikawa - 44 (1080p) [71C5C7A5] (2) (2023): no direct play video profile exists for http/mkv/h264/aac
Jun 17, 2023 00:58:20.764 [16488] DEBUG - [Req#588d1/Transcode] MDE: [SubsPlease] Chiikawa - 44 (1080p) [71C5C7A5] (2) (2023): transcoding selected subtitle stream
Jun 17, 2023 00:58:20.764 [16488] DEBUG - [Req#588d1/Transcode] MDE: [SubsPlease] Chiikawa - 44 (1080p) [71C5C7A5] (2) (2023): selected media 0 / 543280
Jun 17, 2023 00:58:20.764 [16488] DEBUG - [Req#588d1/Transcode] Streaming Resource: Added session 0000018B32C51D90:lf6kcae8yzn5o1ef8tyfiewd
Jun 17, 2023 00:58:20.764 [16488] DEBUG - [Req#588d1/Transcode] Streaming Resource: Reached Decision id=437061 codes=(MDE=1000,Direct play OK.) media=(id=543280 part=(id=552125 decision=direct play protocol=hls streams=(Video=(id=1888192 decision= width=1920 height=1080) Audio=(id=1888193 decision= channels=0 rate=0) Subtitle=(id=1888194 decision=copy languageCode=eng location=sidecar))))
Jun 17, 2023 00:58:20.765 [20400] DEBUG - Completed: [192.168.5.223:34222] 200 GET /video/:/transcode/universal/decision?directPlay=1&directStream=1&directStreamAudio=1&protocol=hls&fastSeek=1&path=%2Flibrary%2Fmetadata%2F437061&session=rx52uxva2n8kfabkqq2q8fx9&mediaIndex=0&partIndex=0&mediaBufferSize=50000&hasMDE=1&subtitleSize=100&videoQuality=100&videoResolution=3840x2160&audioBoost=700&subtitles=sidecar&location=lan (8 live) #588d1 GZIP 14ms 1718 bytes (pipelined: 81)

The logic seems to decide to Not transcode, not sure…But later the transcoder will start anyways, and of course, before the end kick the bucket.

Then the same video, but without subtitles

Jun 17, 2023 00:57:14.593 [8156] DEBUG - [Req#5880c/Transcode] Downloading document http://127.0.0.1:32400/library/metadata/437060?includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=lf6kcae8yzn5o1ef8tyfiewd
Jun 17, 2023 00:57:14.602 [8156] DEBUG - [Req#5880c/Transcode/Req#5880d] We're going to try to auto-select an audio stream for account 1.
Jun 17, 2023 00:57:14.602 [8156] DEBUG - [Req#5880c/Transcode/Req#5880d] Selecting best audio stream for part ID 552124 (language: es)
Jun 17, 2023 00:57:14.602 [8156] DEBUG - [Req#5880c/Transcode/Req#5880d] We're going to try to auto-select a subtitle.
Jun 17, 2023 00:57:14.602 [8156] DEBUG - [Req#5880c/Transcode/Req#5880d] Selected a foreign audio track: 
Jun 17, 2023 00:57:14.602 [8156] DEBUG - [Req#5880c/Transcode/Req#5880d] Audio Stream: 1888191, Subtitle Stream: -1
Jun 17, 2023 00:57:14.603 [8156] DEBUG - [Req#5880c/Transcode] Streaming Resource: Added session 0000018B34579D00:lf6kcae8yzn5o1ef8tyfiewd
Jun 17, 2023 00:57:14.604 [8156] DEBUG - [Req#5880c/Transcode] Streaming Resource: Reached Decision id=437060 codes=(MDE=1000,Direct play OK.) media=(id=543279 part=(id=552124 decision=direct play protocol=hls streams=(Video=(id=1888190 decision= width=1920 height=1080) Audio=(id=1888191 decision= channels=0 rate=0))))
Jun 17, 2023 00:57:14.605 [12276] DEBUG - Completed: [192.168.5.223:34222] 200 GET /video/:/transcode/universal/decision?directPlay=1&directStream=1&directStreamAudio=1&protocol=hls&fastSeek=1&path=%2Flibrary%2Fmetadata%2F437060&session=i0cql02clds77ht2tx9bomxr&mediaIndex=0&partIndex=0&mediaBufferSize=50000&hasMDE=1&subtitleSize=100&videoQuality=100&videoResolution=3840x2160&audioBoost=700&location=lan (7 live) #5880c GZIP 12ms 1590 bytes (pipelined: 38)
Jun 17, 2023 00:57:14.726 [23160] DEBUG - Request: [192.168.5.223:34222 (Allowed Network (Subnet))] GET /:/timeline?key=%2Flibrary%2Fmetadata%2F437060&ratingKey=437060&state=buffering&time=0&duration=136213&playbackTime=0&playQueueItemID=177456&context=home%3Ahub.video.recent&row=9&col=12 (7 live) #5880f GZIP Signed-in Token (mpiva) (LG OLED65G1PUA)

No hangings, went to the end.
Logic also seems to decide not to transcode. But this time, there is No transcoding logging, so I’m guessing it was not transcoded when subtitles are not included.

Supposedly, the Client supports streams with .srt without burning years ago, and .ass streams without burning, like 1 or 2 years ago. So, the, when transcode logic, is strange… Since, stream-wise, H264 and AAC when there is no subtitle, will not transcode, but when added to the mix, it will. As explained. The client is set to Subtitles (BURN NEVER)

Additional Notes 6/18/2023:

Tested several more videos, it seems when the subtitles are near the end of the video this doesn’t happen… Also maybe is an incorrect assumption on my part above. If the transcoder is used to extract and serve the embedded subtitles. In thats the case, it makes sense that the transcoder is fired… ANYWAY, the error seems to be the transcoder kicking the bucket, when serving subtitles. If that is the case.

6

Note: There is another bug with subtitles on the LG Plex app: LG C9 TV: Conversion failed. The transcoder exited due to an error happening occasionally - #68 by cremor

So if you test this one here by skipping/seeking to the end, then maybe you instead trigger the seeking bug.

7

Same here: MKV with internal SRT playing on LG WebOS 6. At the end of the SRT file, the transcoder (that should not be active, it’s direct play for both audio and video, as shown in Dashboard) crashes.

I have crafted an external SRT file that has the last entry AFTER the actual end time of the video, attached it in Plex, and voila, no crash.

So, as a workaround: you can attach an external SRT file (maybe extracted with MKVCleaver), edit it to add a last entry timed after the actual movie end, attached it in Plex and enjoy the movie.

8

Hmm reposting here as i think (not sure) i have the same issue. LG OLED G3 (latest model).

I also see this error in kernel / syslog output. Anyone else getting that?

I provided debug logs in another thread Plex Transcoder segfault libavformat.so.59 - #3 by JaysPlex7

[463692.815417] Plex Transcoder[693374]: segfault at 28 ip 00007fcf7cafc1c2 sp 00007fff84077150 error 4 in libavformat.so.59[7fcf7c97b000+1fe000] likely on CPU 1 (core 1, socket 0)

[463692.815432] Code: 89 ef e8 f1 a8 07 00 45 31 ed 85 c0 41 0f 94 c5 45 85 e4 74 60 83 bb 80 05 00 00 00 74 57 48 8b ab 70 05 00 00 48 89 6c 24 08 <48> 8b 7d 28 e8 65 a5 07 00 eb 62 48 8b bb 80 00 00 00 8b 93 7c 05```
9

Same issue here on my Samsung Tizen TV.
I noticed that this appear to be some sort of regression, as rolling back the Plex Server to v1.32.0.6950 the problem disappears.
Any Plex Server version after that manifests the issue.

1 Like
10

I was able to get this video and was able to reproduce with PMS 1.32.5.7210 and webOS 5.63.0.

This is correct, the transcoder extracts the subtitles from the file. External subs will also be transcoded depending on the format.

I was able to confirm that PMS is returning an error which results in the app showing an error and stopping playback and that it didn’t occur in 1.32.0.6950 and filed an issue against PMS to resolve.

5 Likes
11

@markus101 Any chance that the fix for this bug will also fix the similar error that happens after seeking (see here for details)?

13

Same happening here too. I’m using unRaid with the latest Plex server version.
How to fix this?

Jul  4 16:45:34 kernel: Plex Transcoder[34287]: segfault at 28 ip 0000145da844ad02 sp 00007ffcf1bb21b0 error 4 in libavformat.so.59[145da82ca000+1fe000] likely on CPU 16 (core 4, socket 1)
Jul  4 16:45:34 kernel: Code: 89 ef e8 f1 a8 07 00 45 31 ed 85 c0 41 0f 94 c5 45 85 e4 74 60 83 bb 80 05 00 00 00 74 57 48 8b ab 70 05 00 00 48 89 6c 24 08 <48> 8b 7d 28 e8 65 a5 07 00 eb 62 48 8b bb 80 00 00 00 8b 93 7c 05

Many of my friends are complaining about this, and yes they are using subtitles.

Playback error on: Samsung TV, Google Chrome

Ok on: Plex for Windows

1 Like
14

I’m experiencing the same on my LG G1 and created a separate thread: Playback issue near end of content

I’ll watch this thread, I hope we get a bug fix soon, at least for Samsung and LG players.

As you can see in my thread, there is also a ton of complaints on Reddit regarding this issue.

15

same problem. Every Episode from TV shows crash almost at the end with the same error. I have LG C9 and using PMS 1.32.5.7210. Only happens on My LG TV. no problem happens if i play on FireTV 4k Max, Android phone and tablet, windows…

16

Maybe help but with Xplay on webos work everything fine. Plex client make this errors xplay dont.

17

Dear Markus!

These errors followed after the last good working patch PMServer-1.32.1.6954-56a60573f-x86_64
All versions after this brings these errors what i read above.
I think the key is in these versions, what changed in the transcoding part.
I didn’t tried to solve this with a fresh windows install and the newest PMS install to make a clean registry entries, yet. That would be a good try.

I hope my clue will help us all to solve this.

2 Likes
18

I’ve had this issue on LG C8/C9/C2 for about a month now. Multiple PMS versions and it still persists.

19

Frequently seeing this issue as well. Transcoder error about 2 minutes before the end of an episode. LG. (Also, the first 10 seconds or so of subtitles are always missing too.)

1 Like
20

I have this issue too, started to happen late last year though.
Reported this several times in this forum, never got fixed.
Strange that now so many are having the same issue.

My solution is to set the subtitles to ‘burn always’ in the plex app.
This way everything gets transcoded but at least I can watch my video’s till the end.

21

having the same problem on my lgc7 tv