Server Version#: 1.19.2.2673
Player Version#: web
If I downgrade to v1.18, I don’t seem to have a problem transcoding or playing in general. I have downgraded several times and Plex continues to revert back to 1.19. I have attempted to turn off Subtitles with no luck either.Plex Media Server.5.log (10.0 MB) Plex Media Server.log (8.9 MB)
Two requests:
-
In the future, please do not tag for server-linux-tips as this is an information-providing tag which I maintain.
-
Please recreate your problem then download and attach the log ZIP file
(Settings - Server - Troubleshooting - Download Logs)
I apologize about the incorrect tag, here is the downloaded logs just gathered now. I tried multiple times:
Plex Media Server Logs_2020-04-23_12-24-50.zip (4.9 MB)
I see other devices streaming , apparently without issue but iOS isn’t liking it either due to iOS or the load on the system.
Below, please see the 404 (http NOT FOUND error) when it tries to retrieve the data to send to the player.
There are other transcode sessions active.
example:
Apr 23, 2020 12:24:46.672 [0x7f2c97fff700] DEBUG - Session 02645701-4131-4bb0-aff7-9fa9202d2f4c-702 (3) is unthrottling
Apr 23, 2020 12:24:46.672 [0x7f2c967fc700] DEBUG - [Transcoder] Throttle - Getting back to work.
Apr 23, 2020 12:24:46.702 [0x7f2ca92bc700] DEBUG - Completed: [75.174.183.143:56322] 200 GET /video/:/transcode/universal/session/02645701-4131-4bb0-aff7-9fa9202d2f4c-702/base/00358.ts?protocol=hls&mediaIndex=0&waitForSegments=1 (35 live) TLS 306ms 1060132 bytes (pipelined: 359)
Apr 23, 2020 12:24:46.702 [0x7f2ca92bc700] DEBUG - Removed transcode data consumer, active count 1 => 0
Apr 23, 2020 12:24:46.910 [0x7f2c3e7fc700] DEBUG - Request: [127.0.0.1:53482 (Loopback)] PUT /video/:/transcode/session/A9AA1090-5897-4338-9976-9578C76395D5/21820d8b-3772-4131-ac6c-3870a94d691a/progress?progress=53.4&size=-22&remaining=8125&vdec_packets=41294&vdec_sw_ok=41285&vdec_hw_status=0 (35 live) Signed-in Token (osiris1025) (range: bytes=0-)
Apr 23, 2020 12:24:46.911 [0x7f2ca92bc700] DEBUG - Completed: [127.0.0.1:53482] 206 PUT /video/:/transcode/session/A9AA1090-5897-4338-9976-9578C76395D5/21820d8b-3772-4131-ac6c-3870a94d691a/progress?progress=53.4&size=-22&remaining=8125&vdec_packets=41294&vdec_sw_ok=41285&vdec_hw_status=0 (35 live) 1ms 371 bytes (pipelined: 2081) (range: bytes=0-)
Apr 23, 2020 12:24:46.925 [0x7f2c96ffd700] DEBUG - Request: [127.0.0.1:35858 (Loopback)] PUT /video/:/transcode/session/A9D5B949-23F2-4192-85C3-E04CBC103C7D/4e096334-f9ca-48c2-ae68-8e1fa7e483a5/progress?progress=41.7&size=-22&remaining=4728 (35 live) Signed-in Token (osiris1025) (range: bytes=0-)
Apr 23, 2020 12:24:46.926 [0x7f2ca92bc700] DEBUG - Completed: [127.0.0.1:35858] 206 PUT /video/:/transcode/session/A9D5B949-23F2-4192-85C3-E04CBC103C7D/4e096334-f9ca-48c2-ae68-8e1fa7e483a5/progress?progress=41.7&size=-22&remaining=4728 (35 live) 0ms 371 bytes (pipelined: 342) (range: bytes=0-)
Apr 23, 2020 12:24:47.088 [0x7f2c677fe700] DEBUG - Transcoder segment range: 0 - 383 (382)
Apr 23, 2020 12:24:38.534 [0x7f2c3e7fc700] DEBUG - [Now] Profile is iOS
Apr 23, 2020 12:24:38.534 [0x7f2c3e7fc700] DEBUG - [Now] Updated play state for /library/metadata/2794.
Apr 23, 2020 12:24:38.537 [0x7f2c3e7fc700] DEBUG - Statistics: (EFE94360-9338-43AE-8409-7605BCABDFC7) Reporting active playback in state 0 of type 4 (scrobble: 0) for account 16022778
Apr 23, 2020 12:24:38.547 [0x7f2ca92bc700] DEBUG - Completed: [173.198.168.152:61883] 200 GET /:/timeline?bufferedTime=239&duration=1293652&key=%2Flibrary%2Fmetadata%2F2794&location=wan&playQueueItemID=458436&playbackTime=155754&ratingKey=2794&state=playing&time=155634 (39 live) TLS GZIP 17ms 757 bytes (pipelined: 12)
Apr 23, 2020 12:24:38.631 [0x7f2ca8abb700] DEBUG - Request: [10.0.0.1:23669 (Subnet)] GET /video/:/transcode/universal/dash/77dcb34cyssn2pddsb69wfcn/0/initial.mp4 (38 live) TLS GZIP Signed-in
Apr 23, 2020 12:24:38.632 [0x7f2ca92bc700] DEBUG - Completed: [10.0.0.1:23669] 404 GET /video/:/transcode/universal/dash/77dcb34cyssn2pddsb69wfcn/0/initial.mp4 (38 live) TLS GZIP 0ms 458 bytes (pipelined: 46)
Apr 23, 2020 12:24:38.850 [0x7f2c677fe700] DEBUG - Request: [127.0.0.1:53482 (Loopback)] PUT /video/:/transcode/session/A9AA1090-5897-4338-9976-9578C76395D5/21820d8b-3772-4131-ac6c-3870a94d691a/progress?progress=53.1&size=-22&remaining=19920&vdec_packets=41056&vdec_sw_ok=41047&vdec_hw_status=0 (38 live) Signed-in Token (osiris1025) (range: bytes=0-)
Without seeing a normal PMS startup to verify everything is ok, I am going to make a few assertions.
- This CPU is transcoding, in software, several videos at a time (based on Log activity)
- The logs do not contain a normal startup sequence (due to the amount of work recently done) to see if the inotify table is full. If the table is full, it will cause the 404 error code seen above when Dolby audio tracks are transcoded.
- I am seeing SLOW QUERY messages in the logs. The Scanner became active during playbck (not a big deal provided there is CPU resource for it)
- This CPU has about 1/2 the performance of a 2012 i7-3740qm (mobile)
https://www.cpubenchmark.net/cpu.php?cpu=Intel+Xeon+E5620+%40+2.40GHz&id=1249
If/when possible, seeing a controlled test case will yield the most information.
- Server restart
- Let remain idle for 2 minutes to completely stabilize after startup
- A single playback session of one scenario which recreates the problem.
- Stop that Playback
- Download the Logs ZIP and attach please.
Thank you for the info!
I have been able to successfully support up to 12 simultaneous streams without degradation that I’ve seen in the past. Not since the 1.19 update.
I followed your request instructions, here is the latest Logs ZIP
Plex Media Server Logs_2020-04-23_13-16-15.zip (3.9 MB)
I did notice on this something a little different. Typically when I play from web, it will try to Automatically convert and I have to change it to Original to play. This time, it played the original without me changing anything and I had to change it to Automatically convert to manifest the problem. Hope that helps. Thank you very much helping me figure this out!
Thanks for the log!
I found it.
e708abeeffda/progress/stream?index=0&id=1&codec=h264&type=video (20 live) Signed-in Token (osiris1025) (range: bytes=0-)
Apr 23, 2020 13:15:15.964 [0x7fd1729f3700] DEBUG - Completed: [127.0.0.1:33622] 200 PUT /video/:/transcode/session/jszj3zvsgj9zktitn5f0pjju/a2ee862f-6552-40b7-a01b-e708abeeffda/progress/stream?index=0&id=1&codec=h264&type=video (20 live) 0ms 195 bytes (pipelined: 5) (range: bytes=0-)
Apr 23, 2020 13:15:15.964 [0x7fd0de7fc700] ERROR - [Transcoder] Unknown decoder 'aac_lc'
Apr 23, 2020 13:15:15.967 [0x7fd17c839700] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Transcoder' exit code for process 11632 is 1 (failure)
Apr 23, 2020 13:15:15.968 [0x7fd1627fc700] DEBUG - Streaming Resource: Terminating session 0x7fd0d002c0b0:udkoej8yyazj9jcmiae9aj1m which is using transcoder slot. Used slots is now 0
Apr 23, 2020 13:15:15.968 [0x7fd1627fc700] DEBUG - Streaming Resource: Terminated session 0x7fd0d002c0b0:udkoej8yyazj9jcmiae9aj1m with reason Conversion failed. The transcoder exited due to an error.
Apr 23, 2020 13:15:15.968 [0x7fd0deffd700] DEBUG - Using default transcode duration of 120 minutes
The AAC_LC codec is damaged.
If missing, PMS will download it. This error, “not found” tells me that it didnt’ find a “usable codec”.
The conclusion is “damaged during download”
- Stop Plex
- shell window
sudo sh (or use bash -- your choice)
cd "/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Codecs"
look around in there for any aac codecs. (decoder or encoder)
Remove them (rm -f)
When all cleaned up, Restart plex
Attempt playback again.
There will be a slight pause as it downloads the codecs again.
1 Like
Go down into the x86_64 directory and peek around. They’ll be in there.
Thank you! I even searched for aac and did not find anything. I removed 3 files:
sudo rm -f libaac_decoder.so libaac_encoder.so libaac_lc_decoder.so
restarted PMS and it seems to be working as expected now. I will test with a few more files. Is it common to have codecs get corrupted like that? Is this a regular troubleshooting step I can perform?
It’s not common for them to become corrupted but, since I spotted the symptom immediately, does occur from time to time. The server does a pretty good job at catching errors but isn’t perfect.
This is the regular procedure.
- Identify a problematic codec
- Stop PMS
- Remove the corresponding decoder & encoder
- Start PMS
- Play something using that codec to download a fresh copy.
1 Like
This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.
