Server Version#: v1.24.5.5173-8dcc73a59
Synology: 1515+ / DSM 6.2.4.25556-2
This may be entirely my doing, but I’d like a second opinion on whether what I’ve done has caused the problem.
Last weekend I uploaded some photos and enabled the photo library, which I’d not used before. At that stage the Plex server seemed to crash; could be coincidence. So I downloaded the latest server version, just in case, and installed that, but afterwards the server would not start, and the logs don’t seem to indicate why. Looking at it this morning I wonder if I’d mistakenly installed the DSM7 version, so I downloaded the DSM 6 latest version (1.24.5.5173-8dcc73a59 from the Media Server Downloads | Plex Media Server for Windows, Mac, Linux, FreeBSD and More and selecting “Synology” as the server platform (NOT Synology DSM 7) and installed that, but it staill crashes. This time hover, I see the following in the logs, the latest “Plex Media Server.log”:
Oct 29, 2021 11:17:07.829 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
This looks to me like the attempted installation of the DSM7 version had changed the database schema, and now this DSM6 version can’t open the new schema, but it does say the forward migration was completed. I’ve pasted the foll log at the end.
Although the version numbers on the downloads page are the same for both the DSM 6 and 7 versions, and in DSM Package Center for PMS, it shows the newest online version as 1.18.5.2309-f5213a238. There doesn’t seem to be a link to older package versions on the download page.
So, what are my options here? I was hoping not to install DSM7 as I’ve a Zigbee USB stick in my Synology that isn’t supported by DSM7, but I can live without that if I have to. Or, is there something else I can do to fix this
Oct 29, 2021 11:16:23.958 [0x7f82aac53b38] INFO - Plex Media Server v1.24.5.5173-8dcc73a59 - Synology DS1515+ x86_64 - build: linux-x86_64 synology - GMT 01:00
Oct 29, 2021 11:16:23.959 [0x7f82aac53b38] INFO - Linux version: DSM 6.2.4.25556-2, language: en-US
Oct 29, 2021 11:16:23.959 [0x7f82aac53b38] INFO - Processor Intel(R) Atom™ CPU C2538 @ 2.40GHz
Oct 29, 2021 11:16:23.959 [0x7f82aac53b38] INFO - Compiler is - Clang 11.0.1 (https://plex.tv e0c29d5827bc4eaaa2ceb882cbeed224b0960173)
Oct 29, 2021 11:16:23.959 [0x7f82aac53b38] INFO - /var/packages/Plex Media Server/target/Plex Media Server
Oct 29, 2021 11:16:23.983 [0x7f82ae2906e8] DEBUG - BPQ: [Idle] → [Starting]
Oct 29, 2021 11:16:23.991 [0x7f82ae2906e8] DEBUG - FeatureManager: Using cached data for features list
Oct 29, 2021 11:16:23.993 [0x7f82ae2906e8] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.35.5, threadsafe=1
Oct 29, 2021 11:16:23.995 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 283, recovered 941 frames from WAL file /volume1/Plex/Library/Application Support/Plex Media Server/Plug-in Support/Databases/com.plexapp.plugins.library.db-wal
Oct 29, 2021 11:16:24.098 [0x7f82ae2906e8] DEBUG - Running migrations. (EPG 0)
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Running forward migration 202109061500.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 0.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 1.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 2.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 3.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 4.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 5.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 6.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 7.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 8.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 9.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 10.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 11.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 12.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 13.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 14.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 15.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 16.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 17.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 18.
Oct 29, 2021 11:16:24.099 [0x7f82ae2906e8] DEBUG - Captured session 19.
Oct 29, 2021 11:16:25.903 [0x7f82a9ffcb38] DEBUG - [JobRunner] Job running: ‘/volume1/@appstore/Plex Media Server/CrashUploader’ ‘–directory=/volume1/Plex/Library/Application Support/Plex Media Server/Crash Reports/1.24.5.5173-8dcc73a59’ ‘–version=1.24.5.5173-8dcc73a59’ ‘–platform=Linux’ ‘–platformVersion=DSM 6.2.4.25556-2’ ‘–serverUuid=380e0ec996d4bd6e4e1d88d72757c5d3e20bd2ba’ ‘–userId=dave@ipona.com’ ‘–sentryUrl=https://sentry.io/api/1233455/minidump’ ‘–sentryKey=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx’ ‘–vendor=Synology’ ‘–model=x86_64’ ‘–device=DS1515+’ ‘–allowRetries=0’
Oct 29, 2021 11:16:25.903 [0x7f82a9ffcb38] DEBUG - [JobRunner] Jobs: Starting child process with pid 30615
Oct 29, 2021 11:16:25.927 [0x7f82aac12b38] DEBUG - Jobs: ‘/volume1/@appstore/Plex Media Server/CrashUploader’ exit code for process 30615 is 0 (success)
Oct 29, 2021 11:16:40.551 [0x7f82ae2906e8] WARN - Held transaction for too long (…/Library/FullTextSearch.cpp:58): 16.210000 seconds
Oct 29, 2021 11:16:44.167 [0x7f82ae2906e8] WARN - Held transaction for too long (…/Library/FullTextSearch.cpp:71): 2.900000 seconds
Oct 29, 2021 11:16:47.347 [0x7f82ae2906e8] WARN - Held transaction for too long (…/Library/FullTextSearch.cpp:81): 3.000000 seconds
Oct 29, 2021 11:16:49.652 [0x7f82ae2906e8] WARN - Held transaction for too long (…/Library/FullTextSearch.cpp:94): 2.260000 seconds
Oct 29, 2021 11:16:49.653 [0x7f82ae2906e8] WARN - Held transaction for too long (…/Library/DatabaseMigrations.cpp:237): 24.690000 seconds
Oct 29, 2021 11:16:50.182 [0x7f82ae2906e8] DEBUG - Analyzing database.
Oct 29, 2021 11:16:51.772 [0x7f82ae2906e8] DEBUG - Vacuuming database.
Oct 29, 2021 11:17:07.829 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.834 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.839 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.844 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.849 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.855 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.860 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.865 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.870 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.875 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.880 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.885 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.890 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.896 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.901 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.906 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.911 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.916 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.921 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Completed forward migration 202109061500.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Running forward migration 20210922132300.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 0.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 1.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 2.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 3.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 4.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 5.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 6.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 7.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 8.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 9.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 10.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 11.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 12.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 13.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 14.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 15.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 16.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 17.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 18.
Oct 29, 2021 11:17:07.926 [0x7f82ae2906e8] DEBUG - Captured session 19.
Oct 29, 2021 11:17:07.936 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.941 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.946 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.952 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.957 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.962 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.967 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.972 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.977 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.982 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.987 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.992 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:07.997 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:08.003 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:08.008 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:08.013 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:08.018 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:08.023 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:08.028 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:08.032 [0x7f82ae2906e8] DEBUG - Completed forward migration 20210922132300.
Oct 29, 2021 11:17:08.093 [0x7f82ae2906e8] DEBUG - ChangestampAllocator: initialized to 159607865
Oct 29, 2021 11:17:08.158 [0x7f82ae2906e8] DEBUG - Opening 2 database sessions to library (com.plexapp.plugins.library.blobs), SQLite 3.35.5, threadsafe=1
Oct 29, 2021 11:17:08.835 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 283, recovered 73253 frames from WAL file /volume1/Plex/Library/Application Support/Plex Media Server/Plug-in Support/Databases/com.plexapp.plugins.library.blobs.db-wal
Oct 29, 2021 11:17:08.844 [0x7f82ae2906e8] DEBUG - Running migrations. (EPG 0)
Oct 29, 2021 11:17:08.845 [0x7f82ae2906e8] DEBUG - Running forward migration 202109061500.
Oct 29, 2021 11:17:08.845 [0x7f82ae2906e8] DEBUG - Captured session 0.
Oct 29, 2021 11:17:08.845 [0x7f82ae2906e8] DEBUG - Captured session 1.
Oct 29, 2021 11:17:11.822 [0x7f82ae2906e8] DEBUG - Analyzing database.
Oct 29, 2021 11:17:11.884 [0x7f82ae2906e8] DEBUG - Vacuuming database.
Oct 29, 2021 11:17:15.894 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:15.898 [0x7f82ae2906e8] DEBUG - Completed forward migration 202109061500.
Oct 29, 2021 11:17:15.898 [0x7f82ae2906e8] DEBUG - Running forward migration 20210922132300.
Oct 29, 2021 11:17:15.898 [0x7f82ae2906e8] DEBUG - Captured session 0.
Oct 29, 2021 11:17:15.898 [0x7f82ae2906e8] DEBUG - Captured session 1.
Oct 29, 2021 11:17:15.908 [0x7f82ae2906e8] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Oct 29, 2021 11:17:15.912 [0x7f82ae2906e8] DEBUG - Completed forward migration 20210922132300.
Oct 29, 2021 11:17:15.942 [0x7f82a9d39b38] DEBUG - Codecs: Downloading current version of h264 decoder
Oct 29, 2021 11:17:15.942 [0x7f82a9d39b38] INFO - CodecManager: obtaining decoder ‘h264’
Oct 29, 2021 11:17:15.943 [0x7f82a9d39b38] DEBUG - MyPlex: mapping state set to ‘Unknown’.
Oct 29, 2021 11:17:15.945 [0x7f82a9d39b38] DEBUG - Relay: read 75 cached entries from hosts file
Oct 29, 2021 11:17:15.945 [0x7f82a9d39b38] DEBUG - HTTP requesting GET https://plex.tv/api/codecs/h264_decoder?build=linux-x86_64-standard&deviceId=94c021f5-785e-46bb-8580-8a71b22aba27&oldestPreviousVersion=1.13.5.5291-6fa5e50a8&version=be22e26-4019
Oct 29, 2021 11:17:15.975 [0x7f82ae2906e8] DEBUG - [CERT] Subject name is /CN=*.926fa3b1606246398daecf9d38471b80.plex.direct
Oct 29, 2021 11:17:15.975 [0x7f82ae2906e8] DEBUG - [CERT] Installed certificate with fingerprint 8d:2a:e6:7f:b9:4b:ea:18:ea:87:30:ba:4e:97:90:fa:de:24:ad:ed.
Oct 29, 2021 11:17:15.975 [0x7f82ae2906e8] DEBUG - [CERT/OCSP] Stapling requests will be made to ‘http://r3.o.lencr.org/’.
Oct 29, 2021 11:17:16.005 [0x7f82ae2906e8] INFO - [CERT/OCSP] Successfully retrieved response from cache.
Oct 29, 2021 11:17:16.005 [0x7f82ae2906e8] DEBUG - HttpServer: Listening on IPv6 as well as IPv4.
Oct 29, 2021 11:17:16.005 [0x7f82ae2906e8] DEBUG - HttpServer: Listening on port 32400.
Oct 29, 2021 11:17:16.005 [0x7f82ae2906e8] DEBUG - HttpServer: Listening on port 32401.
Oct 29, 2021 11:17:16.083 [0x7f82a971bb38] DEBUG - Grabber: Cleaning up orphaned grabs.
Oct 29, 2021 11:17:16.085 [0x7f82ae2906e8] DEBUG - Media Provider: Registering provider com.plexapp.plugins.library
Oct 29, 2021 11:17:16.085 [0x7f82a96e3b38] DEBUG - [MediaProviderManager] HTTP requesting GET Sign In | Plex
Oct 29, 2021 11:17:16.085 [0x7f82ae2906e8] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Oct 29, 2021 11:17:16.085 [0x7f82ae2906e8] DEBUG - MyPlex: using cached data for request for https://plex.tv/api/v2/server/access_tokens?auth_token=xxxxxxxxxxxxxxxxxxxx
Oct 29, 2021 11:17:16.085 [0x7f82ae2906e8] DEBUG - MyPlex: updating with 7 access tokens
Oct 29, 2021 11:17:16.087 [0x7f82ae2906e8] DEBUG - MyPlex: using cached data for request for https://plex.tv/api/v2/server/users?auth_token=xxxxxxxxxxxxxxxxxxxx
Oct 29, 2021 11:17:16.100 [0x7f82ae2906e8] DEBUG - MyPlex: using cached data for request for https://plex.tv/api/v2/server/users/subscriptions?auth_token=xxxxxxxxxxxxxxxxxxxx
