Constant crashing while starting - v1.41.2.9200-c6bbc1b53

Plex Media Server
1

Server Version#: v1.41.2.9200-c6bbc1b53
Player Version#: N/A

I have tons of these but, here’s the most current:

Nov 27, 2024 19:47:48.297 [132298263849784] INFO - Plex Media Server v1.41.2.9200-c6bbc1b53 - Docker Docker Container (LinuxServer.io) x86_64 - build: linux-x86_64 debian - GMT 00:00
Nov 27, 2024 19:47:48.297 [132298263849784] INFO - Linux version: 6.12.1-arch1-1, language: en-US
Nov 27, 2024 19:47:48.297 [132298263849784] INFO - Processor: 28-core Intel(R) Core(TM) i7-14700
Nov 27, 2024 19:47:48.297 [132298263849784] INFO - Compiler is - Clang 11.0.1 (https://plex.tv 9b997da8e5b47bdb4a9425b3a3b290be393b4b1f)
Nov 27, 2024 19:47:48.297 [132298263849784] INFO - /usr/lib/plexmediaserver/Plex Media Server
Nov 27, 2024 19:47:48.296 [132298266389136] DEBUG - BPQ: [Idle] -> [Starting]
Nov 27, 2024 19:47:48.297 [132298266389136] DEBUG - FeatureManager: Using cached data for features list
Nov 27, 2024 19:47:48.299 [132298266389136] DEBUG - MyPlex: mapping state set to 'Unknown'.
Nov 27, 2024 19:47:48.299 [132298266389136] DEBUG - Relay: read 51 cached entries from hosts file
Nov 27, 2024 19:47:48.299 [132298266389136] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.39.4, threadsafe=1
Nov 27, 2024 19:47:48.299 [132298266389136] INFO - SQLITE3:0x80000001, 283, recovered 24 frames from WAL file /config/Library/Application Support/Plex Media Server/Plug-in Support/Databases/com.plexapp.plugins.library.db-wal
Nov 27, 2024 19:47:48.320 [132298266389136] DEBUG - MyPlex: using cached data for request for https://plex.tv/api/v2/server/users/features
Nov 27, 2024 19:47:48.332 [132298266389136] DEBUG - [CERT] Subject name is /CN=*.034a93f3ac454055bfa59e3c09a6110f.plex.direct
Nov 27, 2024 19:47:48.332 [132298266389136] DEBUG - [CERT] Installed certificate with fingerprint d1:78:95:0f:b3:3d:33:7b:a7:4b:61:46:76:20:5b:74:0a:18:46:d4.
Nov 27, 2024 19:47:48.332 [132298266389136] DEBUG - [CERT/OCSP] Stapling requests will be made to 'http://e6.o.lencr.org/'.
Nov 27, 2024 19:47:48.332 [132298266389136] INFO - [CERT/OCSP] Successfully retrieved response from cache.
Nov 27, 2024 19:47:48.332 [132298266389136] DEBUG - HttpServer: Listening on IPv6 as well as IPv4.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - HttpServer: Listening on port 32400.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - HttpServer: Listening on port 32401.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Running server...
Nov 27, 2024 19:47:48.333 [132298245917496] DEBUG - HttpServer: Set up a thread pool with 2 threads.
Nov 27, 2024 19:47:48.333 [132298266389136] INFO - Running migrations. (EPG 0)
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 0.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 1.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 2.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 3.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 4.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 5.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 6.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 7.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 8.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 9.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 10.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 11.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 12.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 13.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 14.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 15.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 16.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 17.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 18.
Nov 27, 2024 19:47:48.333 [132298266389136] DEBUG - Captured session 19.
Nov 27, 2024 19:47:48.345 [132298266389136] DEBUG - ChangestampAllocator: initialized to 33038678
Nov 27, 2024 19:47:48.347 [132298266389136] DEBUG - Opening 2 database sessions to library (com.plexapp.plugins.library.blobs), SQLite 3.39.4, threadsafe=1
Nov 27, 2024 19:47:48.349 [132298266389136] INFO - Running migrations. (EPG 0)
Nov 27, 2024 19:47:48.349 [132298266389136] DEBUG - Captured session 0.
Nov 27, 2024 19:47:48.349 [132298266389136] DEBUG - Captured session 1.
Nov 27, 2024 19:47:48.350 [132298266389136] INFO - Killing process: Plex Plug-in [com.plexapp.system] (pid: 7215)
Nov 27, 2024 19:47:48.350 [132298259069752] DEBUG - [GPU] Got device: Raptor Lake-S GT1 [UHD Graphics 770], intel@builtin, default true, best true, ID 8086:a780:1849:a780@0000:00:02.0, DevID [8086:a780:1849:a780], flags 0xbae7
Nov 27, 2024 19:47:48.350 [132298259069752] INFO - Preemptively preparing driver imd for GPU Raptor Lake-S GT1 [UHD Graphics 770]
Nov 27, 2024 19:47:48.350 [132298259069752] DEBUG - [DriverDL/imd] Skipping download; already exists
Nov 27, 2024 19:47:48.351 [132298259069752] INFO - Preemptively preparing driver icr for GPU Raptor Lake-S GT1 [UHD Graphics 770]
Nov 27, 2024 19:47:48.351 [132298259069752] DEBUG - [DriverDL/icr] Skipping download; already exists
Nov 27, 2024 19:47:48.356 [132298259069752] DEBUG - Grabber: Cleaning up orphaned grabs.
Nov 27, 2024 19:47:48.356 [132298266389136] DEBUG - Media Provider: Registering provider com.plexapp.plugins.library
Nov 27, 2024 19:47:48.356 [132298266389136] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Nov 27, 2024 19:47:48.356 [132298266389136] DEBUG - MyPlex: using cached data for request for https://plex.tv/api/v2/server/access_tokens?auth_token=xxxxxxxxxxxxxxxxxxxx
Nov 27, 2024 19:47:48.356 [132298227891000] DEBUG - [MediaProviderManager/HCl#1] HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Nov 27, 2024 19:47:48.357 [132298259069752] DEBUG - Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Nov 27, 2024 19:47:48.357 [132298266389136] DEBUG - MyPlex: updating with 37 access tokens
Nov 27, 2024 19:47:48.359 [132298266389136] DEBUG - MyPlex: using cached data for request for https://plex.tv/api/v2/server/users?auth_token=xxxxxxxxxxxxxxxxxxxx
Nov 27, 2024 19:47:48.359 [132298266389136] DEBUG - MyPlex: using cached data for request for https://plex.tv/api/v2/server/users/subscriptions?auth_token=xxxxxxxxxxxxxxxxxxxx
Nov 27, 2024 19:47:48.360 [132298266389136] DEBUG - MyPlex: using cached data for request for https://plex.tv/api/v2/server/users/services?auth_token=xxxxxxxxxxxxxxxxxxxx
Nov 27, 2024 19:47:48.361 [132298195876664] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Nov 27, 2024 19:47:48.361 [132298195876664] DEBUG - [HCl#2] HTTP requesting GET https://plex.tv/api/v2/server/access_tokens?auth_token=xxxxxxxxxxxxxxxxxxxx
Nov 27, 2024 19:47:48.361 [132298266389136] DEBUG - Opening 1 database sessions to library (), SQLite 3.39.4, threadsafe=1
Nov 27, 2024 19:47:48.362 [132298189265720] DEBUG - File "/usr/lib/plexmediaserver/Resources/Plug-ins-c6bbc1b53" changed: -1 => 2024-11-14 22:39:48 (1731623988).
Nov 27, 2024 19:47:48.362 [132298189265720] DEBUG - File "/config/Library/Application Support/Plex Media Server/Plug-ins" changed: -1 => 2023-11-11 23:36:34 (1699745794).
Nov 27, 2024 19:47:48.362 [132298189265720] DEBUG - Scanning for plug-ins in "/usr/lib/plexmediaserver/Resources/Plug-ins-c6bbc1b53"
Nov 27, 2024 19:47:48.363 [132298189265720] DEBUG - Scanning for plug-ins in "/config/Library/Application Support/Plex Media Server/Plug-ins"
Nov 27, 2024 19:47:48.363 [132298189265720] DEBUG - PluginRepository::setStartState: 1, startingSystem
Nov 27, 2024 19:47:48.363 [132298189265720] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-c6bbc1b53/System.bundle.
Nov 27, 2024 19:47:48.363 [132298189265720] DEBUG - [com.plexapp.system] Setting plug-in to always running (daemon mode).
Nov 27, 2024 19:47:48.363 [132298189265720] DEBUG - Starting file watcher for com.plexapp.system
Nov 27, 2024 19:47:48.363 [132298189265720] DEBUG - Plugin: setting environment variable: 'PYTHONHOME=/usr/lib/plexmediaserver/Resources/Python'
Nov 27, 2024 19:47:48.363 [132298189265720] DEBUG - Plugin: setting environment variable: 'PYTHONPATH=/usr/lib/plexmediaserver/Resources/Python/python27.zip:/usr/lib/plexmediaserver/Resources/Python/lib/python2.7/site-packages'
Nov 27, 2024 19:47:48.364 [132298189265720] DEBUG - Spawned plug-in com.plexapp.system with PID 7297
Nov 27, 2024 19:47:48.364 [132298189265720] DEBUG - [com.plexapp.system] Sending command: GET /:/prefixes

EDIT:

Oh boy! I see my dmesg logs are getting filled with:

[Wed Nov 27 14:47:43 2024] PMS HttpClient[45556]: segfault at ffffffff ip 00000000ffffffff sp 000079ecb3a632e0 error 14 likely on CPU 9 (core 16, socket 0)
[Wed Nov 27 14:47:43 2024] Code: Unable to access opcode bytes at 0xffffffd5.
[Wed Nov 27 14:47:43 2024] PMS HttpClient[45582]: segfault at ffffffff ip 00000000ffffffff sp 000077e6e22f8ce0 error 14 likely on CPU 6 (core 12, socket 0)
[Wed Nov 27 14:47:43 2024] Code: Unable to access opcode bytes at 0xffffffd5.
[Wed Nov 27 14:47:44 2024] PMS HttpClient[45656]: segfault at ffffffff ip 00000000ffffffff sp 00007ff8dc2532e0 error 14 likely on CPU 14 (core 28, socket 0)
[Wed Nov 27 14:47:44 2024] Code: Unable to access opcode bytes at 0xffffffd5.
[Wed Nov 27 14:47:44 2024] PMS HttpClient[45681]: segfault at ffffffff ip 00000000ffffffff sp 0000786cdd31cce0 error 14 likely on CPU 15 (core 28, socket 0)
[Wed Nov 27 14:47:44 2024] Code: Unable to access opcode bytes at 0xffffffd5.
[Wed Nov 27 14:47:47 2024] PMS HttpServer[45757]: segfault at ffffffff ip 00000000ffffffff sp 00007b9e823c1650 error 14 likely on CPU 22 (core 38, socket 0)
[Wed Nov 27 14:47:47 2024] Code: Unable to access opcode bytes at 0xffffffd5.
[Wed Nov 27 14:47:47 2024] PMS HttpClient[45788]: segfault at ffffffff ip 00000000ffffffff sp 000077b5af3aace0 error 14 likely on CPU 0 (core 0, socket 0)
[Wed Nov 27 14:47:47 2024] Code: Unable to access opcode bytes at 0xffffffd5.
[Wed Nov 27 14:47:48 2024] PMS HttpClient[45863]: segfault at ffffffff ip 00000000ffffffff sp 0000785314bfb2e0 error 14 likely on CPU 2 (core 4, socket 0)
[Wed Nov 27 14:47:48 2024] Code: Unable to access opcode bytes at 0xffffffd5.
[Wed Nov 27 14:47:48 2024] PMS HttpClient[45888]: segfault at ffffffff ip 00000000ffffffff sp 00007fd9e457cce0 error 14 likely on CPU 8 (core 16, socket 0)
[Wed Nov 27 14:47:48 2024] Code: Unable to access opcode bytes at 0xffffffd5.
2

Can I have the full DEBUG logs ZIP file?

It’s not possible to diagnose from a snippet this small.

Is this Arch Linux ?

3

Can I have the full DEBUG logs ZIP file?

That’s everything in one of the /config/Library/Application\ Support/Plex\ Media\ Server/Crash\ Reports/1.41.2.9200-c6bbc1b53/PLEX\ MEDIA\ SERVER/ log files. Are there more debug logs somewhere else?

Is this Arch Linux ?

Yes

I think I’ve figured it out. I’m running an agent that monitors network traffic using eBPF. While it’s not open snitch, it’s similar and the issue is identical to what’s reported in systemd seccomp filters cause systemd-timesyncd core dumps on Kernel 6.11 when an app uses eBPF modules · Issue #1205 · evilsocket/opensnitch · GitHub

Temporary solution seems to be to just stop the agent which removes its eBPF programs avoiding the segfaults. And that the actual fix is to get RFE: update v2.5.x syscall tables to v6.12-rc7 by pcmoore · Pull Request #439 · seccomp/libseccomp · GitHub merged and released.

Anyway sorry for the noise, not a PMS issue directly although it does crash it!