Plex Transcoder Errors?

Plex Media Server
21
  1. Subtitles in the file – It won’t USE the GPU (because you might turn subtitles on.
Aug 02, 2023 20:52:20.599 [139796466174776] DEBUG - [Req#70/Transcode] Streaming Resource: Added session 0x7f24e7eebcd8:bo83t8cx92f8zjx83re93wvk
Aug 02, 2023 20:52:20.600 [139796466174776] DEBUG - [Req#70/Transcode] Streaming Resource: Reached Decision id=246307 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=1001,Direct play not available; Conversion OK.) media=(id=430223 part=(id=625787 decision=transcode container=mp4 protocol=dash streams=(Video=(id=2319481 decision=copy width=1920 height=960) Audio=(id=2319482 decision=transcode bitrate=774 encoder=aac channels=6 rate=48000) Subtitle=(id=2319483 decision=copy languageCode=eng location=sidecar))))
Aug 02, 2023 20:52:20.602 [139796580809528] DEBUG - Completed: [192.168.1.231:60337] 200 GET /video/:/transcode/universal/decision
  1. It didn’t say it couldn’t FIND the GPU. It just didn’t select it due to the subtitles in the file.
Aug 02, 2023 20:52:20.647 [139796464065336] DEBUG - [Req#73/Transcode/Req#74] We're going to try to auto-select an audio stream for account 1.
Aug 02, 2023 20:52:20.647 [139796464065336] DEBUG - [Req#73/Transcode/Req#74] Selecting best audio stream for part ID 625787 (language: en)
Aug 02, 2023 20:52:20.647 [139796464065336] DEBUG - [Req#73/Transcode/Req#74] We're going to try to auto-select a subtitle.
Aug 02, 2023 20:52:20.647 [139796464065336] DEBUG - [Req#73/Transcode/Req#74] Subtitles: Found a candidate subtitle language [en] for a foreign film
Aug 02, 2023 20:52:20.647 [139796464065336] DEBUG - [Req#73/Transcode/Req#74] Audio Stream: 2319482, Subtitle Stream: 2319483
Aug 02, 2023 20:52:20.655 [139796464065336] DEBUG - [Req#73/Transcode] Found session GUID of cmiqs2w50umiu6zkvqzt1bgw in session start.
Aug 02, 2023 20:52:20.655 [139796464065336] DEBUG - [Req#73/Transcode] Cleaning directory for session cmiqs2w50umiu6zkvqzt1bgw ()
Aug 02, 2023 20:52:20.655 [139796464065336] DEBUG - [Req#73/Transcode] Starting a transcode session cmiqs2w50umiu6zkvqzt1bgw at offset -1.0 (state=3)
Aug 02, 2023 20:52:20.655 [139796464065336] DEBUG - [Req#73/Transcode] TPU: hardware transcoding: enabled, but no hardware decode accelerator found
Aug 02, 2023 20:52:20.655 [139796464065336] INFO - [Req#73/Transcode] CodecManager: starting EAE at "/tmp/pms-0dd4be2f-71cd-4e09-99c3-d6651718c646/EasyAudioEncoder"
Aug 02, 2023 20:52:20.656 [139796464065336] DEBUG - [Req#73/Transcode/JobRunner] Job running: "/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Codecs/EasyAudioEncoder-1847-linux-x86_64/EasyAudioEncoder/EasyAudioEncoder"
Aug 02, 2023 20:52:20.656 [139796464065336] DEBUG - [Req#73/Transcode/JobRunner] In directory: "/tmp/pms-0dd4be2f-71cd-4e09-99c3-d6651718c646/EasyAudioEncoder"
Aug 02, 2023 20:52:20.656 [139796464065336] DEBUG - [Req#73/Transcode/JobRunner] Jobs: Starting child process with pid 19697
Aug 02, 2023 20:52:20.656 [139796464065336] DEBUG - [Req#73/Transcode] [Universal] Using local file path instead of URL: /media/Data/PlexMedia/TV/Hijack/Season 1/Hijack.2023.S01E01.Final.Call.1080p.ATVP.WEB-DL.DDP5.1.H.264-NTb[TGx]/Hijack.2023.S01E01.Final.Call.1080p.ATVP.WEB-DL.DDP5.1.H.264-NTb.mkv
Aug 02, 2023 20:52:20.656 [139796464065336] DEBUG - [Req#73/Transcode] TPU: hardware transcoding: final decoder: , final encoder: 
Aug 02, 2023 20:52:20.656 [139796464065336] DEBUG - [Req#73/Transcode/JobRunner] Job running: EAE_ROOT=/tmp/pms-0dd4be2f-71cd-4e09-99c3-d6651718c646/EasyAudioEncoder FFMPEG_EXTERNAL_LIBS='/var/lib/plexmediaserver/Library/Application\ Support/Plex\ Media\ Server/Codecs/8217c1c-4578-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Transcoder" -codec:0 h264 -codec:1 eac3_eae -eae_prefix:1 cmiqs2w50umiu6zkvqzt1bgw_ -noaccurate_seek -analyzeduration 20000000 -probesize 20000000 -i "/media/Data/PlexMedia/TV/Hijack/Season 1/Hijack.2023.S01E01.Final.Call.1080p.ATVP.WEB-DL.DDP5.1.H.264-NTb[TGx]/Hijack.2023.S01E01.Final.Call.1080p.ATVP.WEB-DL.DDP5.1.H.264-NTb.mkv" -map 0:0 -codec:0 copy -filter_complex "[0:1] aresample=async=1:ochl='5.1':rematrix_maxval=0.000000dB:osr=48000[0]" -map "[0]" -metadata:s:1 language=eng -codec:1 aac -b:1 774k -f dash -seg_duration 5 -dash_segment_type mp4 -init_seg_name 'init-stream$RepresentationID$.m4s' -media_seg_name 'chunk-stream$RepresentationID$-$Number%05d$.m4s' -window_size 5 -delete_removed false -skip_to_segment 1 -time_delta 0.0625 -manifest_name "http://127.0.0.1:32400/video/:/transcode/session/cmiqs2w50umiu6zkvqzt1bgw/c1439b17-98c8-4374-bc30-110e9fdedcb3/manifest?X-Plex-Http-Pipeline=infinite" -avoid_negative_ts disabled -map_metadata -1 -map_chapters -1 dash -map 0:2 -metadata:s:0 language=eng -codec:0 copy -strict_ts:0 0 -f segment -segment_format srt -segment_time 1 -segment_header_filename sub-header -segment_start_number 0 -segment_list "http://127.0.0.1:32400/video/:/transcode/session/cmiqs2w50umiu6zkvqzt1bgw/c1439b17-98c8-4374-bc30-110e9fdedcb3/manifest?stream=subtitles&X-Plex-Http-Pipeline=infinite" -segment_list_type csv -segment_list_size 5 -segment_list_separate_stream_times 1 -segment_list_unfinished 1 -fflags +flush_packets "sub-chunk-%05d" -start_at_zero -copyts -vsync cfr -y -nostats -loglevel quiet -loglevel_plex error -progressurl http://127.0.0.1:32400/video/:/transcode/session/cmiqs2w50umiu6zkvqzt1bgw/c1439b17-98c8-4374-bc30-110e9fdedcb3/progress
Aug 02, 2023 20:52:20.656 [139796464065336] DEBUG - [Req#73/Transcode/JobRunner] In directory: "/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-cmiqs2w50umiu6zkvqzt1bgw-c1439b17-98c8-4374-bc30-110e9fdedcb3"
Aug 02, 2023 20:52:20.656 [139796464065336] DEBUG - [Req#73/Transcode/JobRunner] Jobs: Starting child process with pid 19698
Aug 02, 2023 20:52:20.659 [139796491234104] DEBUG - Request: [127.0.0.1:45020 (Loopback)] PUT /video/:/transcode/session/cmiqs2w50umiu6zkvqzt1bgw/c1439b17-98c8-4374-bc30-110e9fdedcb3/progress?status=startup (12 live) #5b Signed-in Token (GreggyBear) (range: bytes=0-)
  1. It’s remuxing the container (using the transcoder to remux – normal)
    – decision=transcode container=mp4 protocol=dash
Aug 02, 2023 20:52:20.600 [139796466174776] DEBUG - [Req#70/Transcode] Streaming Resource: Reached Decision id=246307 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=1001,Direct play not available; Conversion OK.) media=(id=430223 part=(id=625787 decision=transcode container=mp4 protocol=dash streams=(Video=(id=2319481 decision=copy width=1920 height=960) Audio=(id=2319482 decision=transcode bitrate=774 encoder=aac channels=6 rate=48000) Subtitle=(id=2319483 decision=copy languageCode=eng location=sidecar))))
Aug 02, 2023 20:52:20.602 [139796580809528] DEBUG - Completed: [192.168.1.231:60337] 200 GET /video/:/transcode/universal/decision

Here. Play this :slight_smile: (Other videos section)

22

So the issue is that the file has subtitles? I have thousands and thousands of files, the majority of which have subtitles and this hasn’t historically been an issue.

That begs the question then, if it’s not using the GPU, why is the stream crashing?

23

I don’t see the reason. That bothers me too.

I suspect the player is what’s crashing and taking down the transcoder in the process
(from what I see)

Can you make it crash again.

  • Grab the Server logs ZIP file (again)
  • Grab the player logs

https://support.plex.tv/articles/?s=player+logs&search=support_articles

Attach both

24

What happened when you played my test file?

25

Plex Media Server Logs_2023-08-02_21-53-51.zip (1.3 MB)

Latest Logs (Its WebOS so pushed logs to server per settings)

26

Hold one, sorry, wife came home and I forgot to load it

27

It played through

28

GPU showed as being active ?

  • Plex Dashboard
  • nvidia-smi
29

Nope, direct play. Will force a transcode

30

Hardware transcode still played through

31

OK. we need to find out what the client is failing on.

There is ONE issue I know of with a client (can’t remember which :frowning: ) where it will terminate abruptly after the final subtitle text displays (not necessarily the end of the movie / episode)

Is that what you’re seeing?

32

Nope, this is roughly 2/3 through, but if it helps, I’m more than happy to test / log / whatever you need

33

ANY capture of failure is a good thing.

When is stops grab both sets of logs and upload please.

I’ll go through them and then :fish: slap … err… chat with <cough cough> the right folks

:rofl:

34

Is the setting of sending device logs to the server acceptable? (Only other option is the overlay)

35

Latest replication, same episode. At least gives us a starting point to “chat” with them about :wink:
Plex Media Server Logs_2023-08-02_23-27-35.zip (2.1 MB)

36

Got the player logs ? (they are important too)

I need to see when the player puked / saw it end.

37

Is this it?

Playing in Plex/web and then :face_vomiting: ?

plex-4.108.0.32184-ffafb0d.css"
Aug 02, 2023 23:27:14.063 [139796396055352] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-8f4248874/WebClient.bundle/Contents/Resources/chunk-9434-7f0a899023a2d7d6f9a8-plex-4.108.0.32184-ffafb0d.css is 72260 (of total: 72260).
Aug 02, 2023 23:27:14.063 [139796582918968] DEBUG - Completed: [192.168.1.63:50244] 200 GET /web/chunk-9434-7f0a899023a2d7d6f9a8-plex-4.108.0.32184-ffafb0d.css (28 live) #468b4 GZIP 0ms 72260 bytes (pipelined: 9)
Aug 02, 2023 23:27:14.072 [139796400274232] DEBUG - [Req#468c7/Transcode/dyipmgne48jzr6jsasyqqg08/98ad3695-40e9-4055-9112-ad7fb6ae564a] Transcoder segment range: 434 - 541 (541)
Aug 02, 2023 23:27:14.344 [139796404493112] DEBUG - [Req#468d1/Transcode/dyipmgne48jzr6jsasyqqg08/98ad3695-40e9-4055-9112-ad7fb6ae564a] Transcoder segment range: 434 - 542 (542)
Aug 02, 2023 23:27:14.696 [139796497574712] DEBUG - [Req#468d4/Transcode/dyipmgne48jzr6jsasyqqg08/98ad3695-40e9-4055-9112-ad7fb6ae564a] Transcoder segment range: 434 - 543 (543)
Aug 02, 2023 23:27:15.047 [139796400274232] DEBUG - [Req#468d6/Transcode/dyipmgne48jzr6jsasyqqg08/98ad3695-40e9-4055-9112-ad7fb6ae564a] Transcoder segment range: 434 - 544 (544)
Aug 02, 2023 23:27:15.364 [139796497574712] DEBUG - [Req#468d9/Transcode/dyipmgne48jzr6jsasyqqg08/98ad3695-40e9-4055-9112-ad7fb6ae564a] Transcoder segment range: 434 - 545 (545)
Aug 02, 2023 23:27:15.672 [139796396055352] DEBUG - [Req#468db/Transcode/dyipmgne48jzr6jsasyqqg08/98ad3695-40e9-4055-9112-ad7fb6ae564a] Transcoder segment range: 434 - 546 (546)
Aug 02, 2023 23:27:15.964 [139796449741624] DEBUG - [Req#468df/Transcode/dyipmgne48jzr6jsasyqqg08/98ad3695-40e9-4055-9112-ad7fb6ae564a] Transcoder segment range: 434 - 547 (547)
Aug 02, 2023 23:27:16.319 [139796404493112] DEBUG - [Req#468e2/Transcode/dyipmgne48jzr6jsasyqqg08/98ad3695-40e9-4055-9112-ad7fb6ae564a] Transcoder segment range: 434 - 548 (548)
Aug 02, 2023 23:27:16.614 [139796400274232] DEBUG - [Req#468e4/Transcode/dyipmgne48jzr6jsasyqqg08/98ad3695-40e9-4055-9112-ad7fb6ae564a] Transcoder segment range: 434 - 549 (549)
Aug 02, 2023 23:27:17.260 [139796404493112] DEBUG - [Req#468e7/Transcode/dyipmgne48jzr6jsasyqqg08/98ad3695-40e9-4055-9112-ad7fb6ae564a] Transcoder segment range: 434 - 550 (550)
Aug 02, 2023 23:27:17.624 [139796449741624] DEBUG - [Req#468ea/Transcode/dyipmgne48jzr6jsasyqqg08/98ad3695-40e9-4055-9112-ad7fb6ae564a] Transcoder segment range: 434 - 551 (551)
Aug 02, 2023 23:27:17.686 [139796585499448] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Transcoder' exit code for process 31040 is -11 (signal: Segmentation fault)
Aug 02, 2023 23:27:17.686 [139796410821432] DEBUG - Notifying consumer that data is complete.
Aug 02, 2023 23:27:17.686 [139796379224888] DEBUG - [TranscodeOutputStream] Input processing thread exited after writing 0 bytes, m_closed=0, m_endOfFileReached=1, session->isStopped()=0
Aug 02, 2023 23:27:17.686 [139796453960504] DEBUG - Killing job.
Aug 02, 2023 23:27:17.686 [139796453960504] DEBUG - Signalling job ID 31040 with 9
Aug 02, 2023 23:27:17.686 [139796453960504] DEBUG - Job was already killed, not killing again.
Aug 02, 2023 23:27:17.686 [139796453960504] DEBUG - Stopping transcode session dyipmgne48jzr6jsasyqqg08

Both the Android TV and Plex/web have Player DEBUG logs .

  1. Turn on DEBUG logging
  2. After it fails but before closing the session,
  3. The logs are there to be grabbed and placed in a .txt.zip file :slight_smile:
38

Nah, this is Samsung WebOS. It only allows sending to the server or that overlay, there isn’t a way (at least in the KBs you linked) to exfiltrate them directly.

39

Interesting. If forced transcode (hw) it doesn’t crash…

40

Config the player app to send its logs.
Then, after playback, pull the server logs ZIP

The samsung logs should be in there.

This isn’t a server problem at all. It’s a player problem 100%

I am one of those who believe “The Smart TV is only smart when you take it out of the box”.

What happens after that ? FIRMWARE UPDATES / BUG FIXES. LOL