RESOLVED: PMS 1.23.0.4480 - 1.23.1.4528 Crash on macOS Mojave 10.14.16 (Roll back to 1.22.3.4392 works fine) Transcoder Issue on Mac Os?

Plex Media Server Desktops & Laptops
1

Server Version#:1.23.0.4480 & 1.23.0.4482
Player Version#:

Similar behavior on 1.23.0.4438 as reported here (nothing has changed on the Server):

Symptom: Plex media server crashes after [some} time. Similar log entries as the 1.23.0.4438 crash, last entries are generating preview thumbnails. Happy to provide full log dump to a non-public location. In this case my Scheduled tasks start at 2am (posted below), crash was 15 mins later.

Plex Media Server Log a (at time of crash) (tail -f ‘Plex Media Server.log’)
Plex was offline after crash, entries below are the final entries in the latest Plex Media Server.log

May 05, 2021 02:15:16.771 [0x700004af9000] DEBUG - Completed: [127.0.0.1:62492] 404 PUT /video/:/transcode/session/bif/883d2cca-be0a-4844-95a9-45b889f8d27d/progress/streamDetail?index=0&id=480&codec=mpeg1video&type=video&bitrate=1228800&width=352&height=240&interlaced=0&sar=1:1&frameRate=29.970 (5 live) 0ms 288 bytes (pipelined: 1) (range: bytes=0-)
May 05, 2021 02:15:16.771 [0x7000049f3000] DEBUG - Request: [127.0.0.1:62493 (Loopback)] PUT /video/:/transcode/session/bif/883d2cca-be0a-4844-95a9-45b889f8d27d/progress/streamDetail?index=1&id=448&codec=mp2&type=audio&bitrate=224000&channels=2&layout=stereo&sampleRate=44100 (5 live) Signed-in Token (******) (range: bytes=0-)
May 05, 2021 02:15:16.771 [0x700004af9000] DEBUG - Completed: [127.0.0.1:62493] 404 PUT /video/:/transcode/session/bif/883d2cca-be0a-4844-95a9-45b889f8d27d/progress/streamDetail?index=1&id=448&codec=mp2&type=audio&bitrate=224000&channels=2&layout=stereo&sampleRate=44100 (5 live) 0ms 288 bytes (pipelined: 1) (range: bytes=0-)
May 05, 2021 02:15:16.772 [0x7000049f3000] DEBUG - Request: [127.0.0.1:62494 (Loopback)] PUT /video/:/transcode/session/bif/883d2cca-be0a-4844-95a9-45b889f8d27d/progress?duration=1374.439733 (5 live) Signed-in Token () (range: bytes=0-)
May 05, 2021 02:15:16.772 [0x700004a76000] DEBUG - Completed: [127.0.0.1:62494] 206 PUT /video/:/transcode/session/bif/883d2cca-be0a-4844-95a9-45b889f8d27d/progress?duration=1374.439733 (5 live) 0ms 371 bytes (pipelined: 1) (range: bytes=0-)
May 05, 2021 02:15:17.273 [0x7000049f3000] DEBUG - [Transcode] Activity: updated activity 883d2cca-be0a-4844-95a9-45b889f8d27d - completed 17.3% - Generating video preview thumbnails
May 05, 2021 02:15:17.773 [0x7000049f3000] DEBUG - [Transcode] Activity: updated activity 883d2cca-be0a-4844-95a9-45b889f8d27d - completed 34.9% - Generating video preview thumbnails
May 05, 2021 02:15:18.276 [0x7000049f3000] DEBUG - [Transcode] Activity: updated activity 883d2cca-be0a-4844-95a9-45b889f8d27d - completed 52.8% - Generating video preview thumbnails
May 05, 2021 02:15:18.777 [0x7000049f3000] DEBUG - [Transcode] Activity: updated activity 883d2cca-be0a-4844-95a9-45b889f8d27d - completed 70.7% - Generating video preview thumbnails
May 05, 2021 02:15:19.277 [0x7000049f3000] DEBUG - [Transcode] Activity: updated activity 883d2cca-be0a-4844-95a9-45b889f8d27d - completed 88.5% - Generating video preview thumbnails

Scheduled tasks:

Screen Shot 2021-05-05 at 7.58.15 AM
Screen Shot 2021-05-05 at 7.58.15 AM573×873 103 KB

2

Just tried 1.23.1.4528 same issue. Tried disabling hardware transcoding. Still crashes. Back on 1.22.3.4392 now which is stable. Possible to engage on this Plex Team? Thanks!

3

Same issue as -

4

Mic check Plex Team… This thing on? :slight_smile:

5

Same issue through 1.23.1.4602

Roll back to 1.22.3.4392 works fine…

7

This is now resolved. I’m currently on Plex 1.23.4.4712 and it made through scheduled tasks cycle with no issues. This issue was, whatever Plex changed in 1.23 branch in the transcoder was causing a crash on one file in my library. Here’s how I found it. Below is an excerpt from my Plex Media Server.log file as it crashed. (Set Plex in Debug mode to capture)

At the top of the log notice it starts a new Activity “Generating video preview thumbnails” and assigned it with an activity ID of 18e16a03-2c1a-44ca-a379-c3a9b71a0bf1. You can follow that activity ID all the way down to the very last entry where it’s generating thumbnails. The last entry in the log (at the very bottom) shows it as at 86% for the “Blahblahblah-video-file E01” with process uuid of “18e16a03-2c1a-44ca-a379-c3a9b71a0bf1”. That ties the issue to that file. I removed that file, set scheduled maintenance to run the very next hour and it completed no issue. All hardware acceleration is enabled and Plex seems stable now. Glad to check this off -

Jun 23, 2021 02:06:09.552 [0x70000e966000] DEBUG - Activity: registered new activity 18e16a03-2c1a-44ca-a379-c3a9b71a0bf1 - "Generating video preview thumbnails"
Jun 23, 2021 02:06:09.552 [0x70000e966000] DEBUG - [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/Users/PlexUser/Library/Application\ Support/Plex\ Media\ Server/Codecs/fa235d6-3858-darwin-x86_64/' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/Applications/Plex Media Server.app/Contents/MacOS/Plex Transcoder' '-codec:v' 'mpeg1video' '-skip_frame:v' 'nokey' '-i' '/Volumes/Plex Media/Plex TV Shows /THE SHOW/blahblahblah-video-file.mpg' '-threads' '0' '-nostats' '-q' '3' '-loglevel' 'quiet' '-filter_complex' '[0:V:0] fps=fps=0.500000:round=up,scale=w=320:h=240:force_original_aspect_ratio=decrease [out]' '-map' '[out]' '/Users/PlexUser/Library/Application Support/Plex Media Server/Media/localhost/d/ff2cd3d6eaf9a1a1660360df03ed3446b05cfa8.bundle/Contents/Indexes/tmp/img-%06d.jpg' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress'
Jun 23, 2021 02:06:09.553 [0x70000e966000] DEBUG - [JobRunner] Jobs: Starting child process with pid 65979
Jun 23, 2021 02:06:09.570 [0x70000ee01000] DEBUG - Request: [127.0.0.1:50643 (Loopback)] PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress?status=startup (7 live) Signed-in Token (PlexUsername) (range: bytes=0-)
Jun 23, 2021 02:06:09.571 [0x70000ecfb000] DEBUG - Completed: [127.0.0.1:50643] 206 PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress?status=startup (7 live) 0ms 371 bytes (pipelined: 1) (range: bytes=0-)
Jun 23, 2021 02:06:09.583 [0x70000ee01000] DEBUG - Request: [127.0.0.1:50643 (Loopback)] PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress?status=startup (7 live) Signed-in Token (PlexUsername) (range: bytes=0-)
Jun 23, 2021 02:06:09.583 [0x70000ed7e000] DEBUG - Completed: [127.0.0.1:50643] 206 PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress?status=startup (7 live) 0ms 371 bytes (pipelined: 2) (range: bytes=0-)
Jun 23, 2021 02:06:09.584 [0x70000ee01000] DEBUG - Request: [127.0.0.1:50643 (Loopback)] PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress?status=opening (7 live) Signed-in Token (PlexUsername) (range: bytes=0-)
Jun 23, 2021 02:06:09.584 [0x70000ecfb000] DEBUG - Completed: [127.0.0.1:50643] 206 PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress?status=opening (7 live) 0ms 371 bytes (pipelined: 3) (range: bytes=0-)
Jun 23, 2021 02:06:09.600 [0x70000ee01000] DEBUG - Request: [127.0.0.1:50643 (Loopback)] PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress?status=opened (7 live) Signed-in Token (PlexUsername) (range: bytes=0-)
Jun 23, 2021 02:06:09.600 [0x70000ed7e000] DEBUG - Completed: [127.0.0.1:50643] 206 PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress?status=opened (7 live) 0ms 371 bytes (pipelined: 4) (range: bytes=0-)
Jun 23, 2021 02:06:09.621 [0x70000ee01000] DEBUG - Request: [127.0.0.1:50643 (Loopback)] PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress/stream?index=0&id=480&codec=mpeg1video&type=video (7 live) Signed-in Token (PlexUsername) (range: bytes=0-)
Jun 23, 2021 02:06:09.621 [0x70000ecfb000] DEBUG - Completed: [127.0.0.1:50643] 404 PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress/stream?index=0&id=480&codec=mpeg1video&type=video (7 live) 0ms 288 bytes (pipelined: 5) (range: bytes=0-)
Jun 23, 2021 02:06:09.621 [0x70000ee01000] DEBUG - Request: [127.0.0.1:50644 (Loopback)] PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress/stream?index=0&id=480&codec=mpeg1video&type=video (7 live) Signed-in Token (PlexUsername) (range: bytes=0-)
Jun 23, 2021 02:06:09.622 [0x70000ed7e000] DEBUG - Completed: [127.0.0.1:50644] 404 PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress/stream?index=0&id=480&codec=mpeg1video&type=video (7 live) 0ms 288 bytes (pipelined: 1) (range: bytes=0-)
Jun 23, 2021 02:06:09.622 [0x70000ee01000] DEBUG - Request: [127.0.0.1:50645 (Loopback)] PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress/stream?index=1&id=448&codec=mp2&type=audio (7 live) Signed-in Token (PlexUsername) (range: bytes=0-)
Jun 23, 2021 02:06:09.622 [0x70000ecfb000] DEBUG - Completed: [127.0.0.1:50645] 404 PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress/stream?index=1&id=448&codec=mp2&type=audio (7 live) 0ms 288 bytes (pipelined: 1) (range: bytes=0-)
Jun 23, 2021 02:06:09.623 [0x70000ee01000] DEBUG - Request: [127.0.0.1:50646 (Loopback)] PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress/streamDetail?index=0&id=480&codec=mpeg1video&type=video&bitrate=1228800&width=352&height=240&interlaced=0&sar=1:1&frameRate=29.970 (7 live) Signed-in Token (PlexUsername) (range: bytes=0-)
Jun 23, 2021 02:06:09.624 [0x70000ed7e000] DEBUG - Completed: [127.0.0.1:50646] 404 PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress/streamDetail?index=0&id=480&codec=mpeg1video&type=video&bitrate=1228800&width=352&height=240&interlaced=0&sar=1:1&frameRate=29.970 (7 live) 0ms 288 bytes (pipelined: 1) (range: bytes=0-)
Jun 23, 2021 02:06:09.624 [0x70000ee01000] DEBUG - Request: [127.0.0.1:50647 (Loopback)] PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress/streamDetail?index=1&id=448&codec=mp2&type=audio&bitrate=224000&channels=2&layout=stereo&sampleRate=44100 (8 live) Signed-in Token (PlexUsername) (range: bytes=0-)
Jun 23, 2021 02:06:09.624 [0x70000ed7e000] DEBUG - Completed: [127.0.0.1:50647] 404 PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress/streamDetail?index=1&id=448&codec=mp2&type=audio&bitrate=224000&channels=2&layout=stereo&sampleRate=44100 (8 live) 0ms 288 bytes (pipelined: 1) (range: bytes=0-)
Jun 23, 2021 02:06:09.626 [0x70000ee01000] DEBUG - Request: [127.0.0.1:50648 (Loopback)] PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress?duration=1374.439733 (8 live) Signed-in Token (PlexUsername) (range: bytes=0-)
Jun 23, 2021 02:06:09.626 [0x70000ed7e000] DEBUG - Completed: [127.0.0.1:50648] 206 PUT /video/:/transcode/session/bif/18e16a03-2c1a-44ca-a379-c3a9b71a0bf1/progress?duration=1374.439733 (8 live) 0ms 371 bytes (pipelined: 1) (range: bytes=0-)
Jun 23, 2021 02:06:10.127 [0x70000ee01000] DEBUG - [Transcode] Activity: updated activity 18e16a03-2c1a-44ca-a379-c3a9b71a0bf1 - completed 22.1% - Generating video preview thumbnails
Jun 23, 2021 02:06:10.695 [0x70000ee01000] DEBUG - [Transcode] Activity: updated activity 18e16a03-2c1a-44ca-a379-c3a9b71a0bf1 - completed 40.2% - Generating video preview thumbnails
Jun 23, 2021 02:06:11.195 [0x70000ee01000] DEBUG - [Transcode] Activity: updated activity 18e16a03-2c1a-44ca-a379-c3a9b71a0bf1 - completed 63.0% - Generating video preview thumbnails
Jun 23, 2021 02:06:11.695 [0x70000ee01000] DEBUG - [Transcode] Activity: updated activity 18e16a03-2c1a-44ca-a379-c3a9b71a0bf1 - completed 86.0% - Generating video preview thumbnails
1 Like
8

This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.