Server Version#: 1.30.1.6483 (problem still there with 1.30.1.6497)
For short, my server keeps “crashing” (it becomes unavailable with app.plex.tv, webui with local IP or any client but service seems to be still up and running when I check over cli) ; when I sudo service plexmediaserver stop/start everything is back online until it randomly crashes again.
I’ve done basic stuff like, Plex Dance , Bundles cleaning, database check+repair (with PlexDBRepair in 1-4-3 sequence)
I didn’t change any setting on my server. I can’t find any relevant information in the logs, but I suspect it might be related to the transcoder.
nothing’s bloated :
user is plex:plex (uid=1000(plex) gid=1000(plex))
I keep having those in the console in loop while there’s no media being played…
Warning: [Req#2cfba/Transcode] Denying access due to session lacking permission to transcode key /library/metadata/105135
Error: [Req#bf4/Transcode] Denying access due to session lacking permission to transcode key /library/metadata/105135
Error: [TranscodeOutputStream] Session appears to have died from under us
I’ve cleanup the logs, run the server until I found it “crashed” again and restart it, here’re the logs (vers. 1.30.1.6483):Plex Media Server Logs_2022-12-14_20-39-02.zip (1.4 MB)
Edit: From what I’ve observed, when it crashes, ongoing streaming keep going until the client app pause/stop or go back to the main menu from there the client is told that the server is unavailable with no content available.
Problem is still there with last release (1.30.1.6497) ; it grinds my gears I can’t figure out what’s happening.
Fortunately (or not) I tar -cvzf /var/lib/plexmediaserver every week, so I might be able to restore my plex from when it worked 1 or 2 weeks ago.
it seems there’s no official support guide to fully restore from such backup.
Should I just :
stop PMS
mv /var/lib/plexmediaserver /var/lib/plexmediaserver_old
tar xvf backup.tar.gz -C /var/lib/plexmediaserver
chwon plex:plex /var/lib/plexmediaserver
start PMS
enjoy?
seems to be too easy to be true xD
benf999
December 16, 2022, 6:33pm
5
I noticed this started last night for me with version 1.30.1.6497 on Windows 11. Guess I’ll need to learn how to read Plex logs! Only stays up 10 minutes or so it seems
ChuckPa
December 16, 2022, 8:13pm
6
@Hergonoway
You have a CoffeeLake CPU which PMS is seeing but HW transcoding isn’t enabling.
Do you have all the options on? I also see your FR subtitles
Also, have you compared with 1.29.2 (Known stable and working for your TrueHD audio) ?
Dec 14, 2022 20:38:19.008 [0x7fde33515b38] DEBUG - [Req#21/Transcode] Streaming Resource: Reached Decision id=109956 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=187972 part=(id=236613 decision=transcode container=mp4 protocol=dash streams=(Video=(id=1126966 decision=copy width=3840 height=2160) Audio=(id=1126967 decision=transcode bitrate=1032 encoder=eac3_eae channels=8 rate=48000) Subtitle=(id=1126969 decision=copy languageCode=fra location=sidecar))))
Dec 14, 2022 20:38:19.008 [0x7fde33515b38] DEBUG - [Req#21/Transcode] Found session GUID of hi78g3xvil01wcdiay3uh0c1 in session start.
Dec 14, 2022 20:38:19.008 [0x7fde33515b38] DEBUG - [Req#21/Transcode] Cleaning directory for session hi78g3xvil01wcdiay3uh0c1 ()
Dec 14, 2022 20:38:19.008 [0x7fde33515b38] DEBUG - [Req#21/Transcode] Starting a transcode session hi78g3xvil01wcdiay3uh0c1 at offset -1.0 (state=3)
Dec 14, 2022 20:38:19.008 [0x7fde33515b38] DEBUG - [Req#21/Transcode] TPU: hardware transcoding: enabled, but no hardware decode accelerator found
Dec 14, 2022 20:38:19.008 [0x7fde33515b38] INFO - [Req#21/Transcode] CodecManager: starting EAE at "/tmp/pms-b8e732e3-87a3-4b8a-a4f3-8c90b485b16d/EasyAudioEncoder"
Dec 14, 2022 20:38:19.008 [0x7fde33515b38] DEBUG - [Req#21/Transcode/JobRunner] Job running: "/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Codecs/EasyAudioEncoder-1847-linux-x86_64/EasyAudioEncoder/EasyAudioEncoder"
Dec 14, 2022 20:38:19.008 [0x7fde33515b38] DEBUG - [Req#21/Transcode/JobRunner] In directory: "/tmp/pms-b8e732e3-87a3-4b8a-a4f3-8c90b485b16d/EasyAudioEncoder"
Dec 14, 2022 20:38:19.009 [0x7fde33515b38] DEBUG - [Req#21/Transcode/JobRunner] Jobs: Starting child process with pid 6700
Dec 14, 2022 20:38:19.009 [0x7fde33515b38] DEBUG - [Req#21/Transcode] [Universal] Using local file path instead of URL: /data/video/Films/Top Gun Maverick (2020)/Top Gun Maverick (2022) - (tt1745960)[IMAX][TRUEFRENCH MULTi][FR+EN][TRUEHD ATMOS 7.1][HDR][x265][Bluray-2160p] -QTZ.mkv
Dec 14, 2022 20:38:19.009 [0x7fde33515b38] DEBUG - [Req#21/Transcode] TPU: hardware transcoding: final decoder: , final encoder:
Dec 14, 2022 20:38:19.009 [0x7fde33515b38] DEBUG - [Req#21/Transcode/JobRunner] Job running: EAE_ROOT=/tmp/pms-b8e732e3-87a3-4b8a-a4f3-8c90b485b16d/EasyAudioEncoder FFMPEG_EXTERNAL_LIBS='/var/lib/plexmediaserver/Library/Application\ Support/Plex\ Media\ Server/Codecs/583b3e4-4441-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxx4448-90ad-ebfb0b7af021 "/usr/lib/plexmediaserver/Plex Transcoder" -codec:0 hevc -codec:1 truehd_eae -eae_prefix:1 hi78g3xvil01wcdiay3uh0c1_ -noaccurate_seek -analyzeduration 20000000 -probesize 20000000 -i "/data/video/Films/Top Gun Maverick (2020)/Top Gun Maverick (2022) - (tt1745960)[IMAX][TRUEFRENCH MULTi][FR+EN][TRUEHD ATMOS 7.1][HDR][x265][Bluray-2160p] -QTZ.mkv" -map 0:0 -codec:0 copy -filter_complex "[0:1] aresample=async=1:ochl='7.1':rematrix_maxval=0.000000dB:osr=48000[0]" -map "[0]" -metadata:s:1 language=fra -codec:1 eac3_eae -eae_prefix:1 hi78g3xvil01wcdiay3uh0c1_ -b:1 1000k -break_non_keyframes 1 -f dash -seg_duration 1 -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/hi78g3xvil01wcdiay3uh0c1/eb37eb2a-fb6e-46c9-84ed-58626a02cc9e/manifest?X-Plex-Http-Pipeline=infinite" -format_options f_strict=unofficial -avoid_negative_ts disabled -map_metadata -1 -map_chapters -1 dash -map 0:3 -metadata:s:0 language=fra -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/hi78g3xvil01wcdiay3uh0c1/eb37eb2a-fb6e-46c9-84ed-58626a02cc9e/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/hi78g3xvil01wcdiay3uh0c1/eb37eb2a-fb6e-46c9-84ed-58626a02cc9e/progress
Dec 14, 2022 20:38:19.009 [0x7fde33515b38] DEBUG - [Req#21/Transcode/JobRunner] In directory: "/tmp/transcoding/Transcode/Sessions/plex-transcode-hi78g3xvil01wcdiay3uh0c1-eb37eb2a-fb6e-46c9-84ed-58626a02cc9e"
Dec 14, 2022 20:38:19.009 [0x7fde33515b38] DEBUG - [Req#21/Transcode/JobRunner] Jobs: Starting child process with pid 6701
There is a regression in 1.30.0 and above for TrueHD
Yes all should be correctly enabled, I didn’t changed my settings since a long long time about transcoding.
Also, have you compared with 1.29.2 (Known stable and working for your TrueHD audio) ?
I didn’t rollback to 1.29 but I had no issue with it as far as I remember. This phenomenon appeared since at least the last 2/3 beta releases in 1.30.1.X, I don’t recall having this issue in 1.30.0.X
ChuckPa
December 16, 2022, 9:13pm
8
How versed are you at the command line?
Would you feel up to:
Stop PMS
Run a shell script (with a few commands in that shell script) to clean your databases
Retest to see if PMS behaves again?
I suggest this because I’ve seen many cases where PMS will run “most of the time” then crash without reason because of a “slightly” damaged databse.
I doubt this will help given you’ve rebuilt.
I do suggest 1.29.2 with a purpose. (There were a lot of internal changes in 1.30.0)
Would you feel up to:
Stop PMS
Run a shell script (with a few commands in that shell script) to clean your databases
Retest to see if PMS behaves again?
Yes I would. (just FYI, I’ve already run your light tool PlexDBRepair in 1-4-3 sequence just in case)
ChuckPa
December 16, 2022, 9:18pm
10
That’s the one I was going to suggest.
If that’s not helped then I do suggest you downgrade to 1.29.2 and retest from there
I’ve never performed a rollback on Plex before, shoud I just stop PMS and:sudo dpkg -i plexmediaserver_1.29.2.XXXX_amd64.deb ?
also, I can only grab down to 1.30.0.6486-629d58034 on https://www.plex.tv/media-server-downloads/ do you have an alt link for 1.29.2?
ChuckPa
December 16, 2022, 9:34pm
12
That’s correct. "Install on top of " the existing.
DPKG will handle the executable
PMS will rollback the database migrations for you.
thanks I’ll give it a shot right now!
downgrade to 1.29 is done, I’ll monitor PMS until tomorrow and give you a feedback. Thanks for your support.
ChuckPa
December 16, 2022, 10:26pm
15
Thanks. I’m interesting in knowing what the result is.
Unfortunately, the problem is still there in 1.29
here are the logs just after a restart :Plex Media Server Logs_2022-12-17_13-55-38.zip (1.1 MB)
Edit : attached the right file
ChuckPa
December 17, 2022, 6:08pm
18
@Hergonoway
Does the machine crash/reboot or just PMS ? (Dust bunny problem?)
After downgrading, and running my tool,
(rewrite the contents of whichever record(s) are causing a problem – if that’s the issue
Does the machine crash/reboot or just PMS ? (Dust bunny problem?)
only PMS
After downgrading, and running my tool,
ok I’ll force reinstall in 1.29, then run plexDBrepair in 1-4-3 then refresh all metadata of each library
ChuckPa
December 17, 2022, 6:51pm
20
Hergonoway:
Does the machine crash/reboot or just PMS ? (Dust bunny problem?)
only PMS
CRAP… Smells like bad records.
Bad record content is one thing my shell script can’t do anything about.
