I’ve updated PMS from 1.23 to 1.25 and it is broken for any media. Web UI is working, but nothing is played because of SQLite errors (log is included). Please help.
Server Version#: 1.25.2.5319
Plex Media Server.log (1.5 MB)
Actual for last PMS. Any update?
Looks like a database migration that failed.
Update to the latest version, then wait a bit then grab the full server logs and post that zip file here.
The same on last 1.25.6
Feb 26, 2022 12:40:37.945 [0x8060f3000] DEBUG - Analyzing database.
Feb 26, 2022 12:40:46.121 [0x8060f3000] DEBUG - Vacuuming database.
Feb 26, 2022 12:40:50.997 [0x8060f3000] ERROR - SQLITE3:0x10, 13, statement aborts at 8: [INSERT INTO vacuum_db.'taggings' SELECT*FROM"main".'taggings'] database or disk is full
Feb 26, 2022 12:40:51.007 [0x8060f3000] ERROR - SQLITE3:0x10, 13, statement aborts at 1: [VACUUM] database or disk is full
Feb 26, 2022 12:40:51.023 [0x8060f3000] ERROR - Exception thrown during migrations, aborting: sqlite3_statement_backend::loadOne: database or disk is full
Feb 26, 2022 12:40:52.706 [0x8060f3000] DEBUG - ChangestampAllocator: initialized to 78088136
Feb 26, 2022 12:40:52.740 [0x8060f3000] DEBUG - Opening 2 database sessions to library (com.plexapp.plugins.library.blobs), SQLite 3.35.5, threadsafe=1
Feb 26, 2022 12:40:52.745 [0x8060f3000] INFO - SQLITE3:0x10, 283, recovered 599 frames from WAL file /mnt/ssd/system/plexpass/plexdata/Plex Media Server/Plug-in Support/Databases/com.plexapp.plugins.library.blobs.db-wal
Feb 26, 2022 12:40:52.749 [0x8060f3000] DEBUG - Running migrations. (EPG 0)
Feb 26, 2022 12:40:52.750 [0x8060f3000] DEBUG - Running forward migration 202107070000.
Feb 26, 2022 12:40:52.750 [0x8060f3000] DEBUG - Captured session 0.
Feb 26, 2022 12:40:52.750 [0x8060f3000] DEBUG - Captured session 1.
Feb 26, 2022 12:40:52.750 [0x8060f3000] DEBUG - Analyzing database.
Feb 26, 2022 12:40:53.368 [0x8060f3000] DEBUG - Vacuuming database.
Feb 26, 2022 12:40:55.054 [0x8060f3000] ERROR - SQLITE3:0x10, 13, statement aborts at 7: [INSERT INTO vacuum_db.'blobs' SELECT*FROM"main".'blobs'] database or disk is full
Feb 26, 2022 12:40:55.063 [0x8060f3000] ERROR - SQLITE3:0x10, 13, statement aborts at 1: [VACUUM] database or disk is full
Feb 26, 2022 12:40:55.065 [0x8060f3000] ERROR - Exception thrown during migrations, aborting: sqlite3_statement_backend::loadOne: database or disk is full
Feb 26, 2022 12:46:11.059 [0x807e8ee00] ERROR - SQLITE3:0x10, 1, no such table: metadata_item_setting_markers in "select metadata_item_setting_markers.id as 'metadata_item_setting_markers_id', metadata_item_setting_markers.marker_type as 'metadata_item_setting_markers_marke
Feb 26, 2022 12:46:11.062 [0x807e8ee00] ERROR - Soci Exception handled: sqlite3_statement_backend::prepare: no such table: metadata_item_setting_markers for SQL: select metadata_item_setting_markers.id as 'metadata_item_setting_markers_id', metadata_item_setting_markers.marker_type as 'metadata_item_setting_markers_marker_type', metadata_item_setting_markers.metadata_item_setting_id as 'metadata_item_setting_markers_metadata_item_setting_id', metadata_item_setting_markers.start_time_offset as 'metadata_item_setting_markers_start_time_offset', metadata_item_setting_markers.end_time_offset as 'metadata_item_setting_markers_end_time_offset', metadata_item_setting_markers.title as 'metadata_item_setting_markers_title', metadata_item_setting_markers.created_at as 'metadata_item_setting_markers_created_at', metadata_item_setting_markers.updated_at as 'metadata_item_setting_markers_updated_at', metadata_item_setting_markers.extra_data as 'metadata_item_setting_markers_extra_data' from metadata_item_setting_markers join metadata_item_settings on metadata_item_settings.id = metadata_item_setting_markers.metadata_item_setting_id where metadata_item_settings.guid = ? and metadata_item_settings.account_id = ? order by metadata_item_setting_markers.start_time_offset, metadata_item_setting_markers.end_time_offset
database or disk is full - it’s strange because I have enough disk space
I’m mostly curious if there’s a permission issue on any of the Plex database files. Perhaps Plex was manually launched as root, or something similar.
ls -la "/mnt/ssd/system/plexpass/plexdata/Plex Media Server/Plug-in Support/Databases/"
But regarding disk space … prove it? I think that’s OK, it’s obvious from the logs that Plex is successfully writing other files.
df -h "/mnt/ssd/system/plexpass/plexdata/Plex Media Server/Plug-in Support/Databases/"
I hope it’s either space or permissions. The other possibility is a corrupt database.
If those all look OK, it’s worth checking to see if there are database integrity issues.
"/mnt/ssd/system/plexpass/plexdata/Plex Media Server/Plex SQLite" "/mnt/ssd/system/plexpass/plexdata/Plex Media Server/Plug-in Support/Databases/com.plexapp.plugins.library.db" "PRAGMA integrity_check"
Hi
nas: ~# df -h "/mnt/ssd/system/plexpass/plexdata/Plex Media Server/Plug-in Support/Databases/"
Filesystem Size Used Avail Capacity Mounted on
ssd/system 509G 63G 446G 12% /mnt/ssd/system
nas: ~# ls -la "/mnt/ssd/system/plexpass/plexdata/Plex Media Server/Plug-in Support/Databases/"
total 1430053
drwxr-xr-x 2 plex plex 9 Apr 10 23:11 .
drwxr-xr-x 7 plex plex 7 May 22 2019 ..
-rw-r--r-- 1 plex plex 17453056 Apr 8 22:55 com.plexapp.dlna.db
-rw-r--r-- 1 plex plex 163840 Apr 8 23:56 com.plexapp.dlna.db-shm
-rw-r--r-- 1 plex plex 17862144 Apr 8 22:55 com.plexapp.dlna.db-wal
-rw-r--r-- 1 plex plex 1002742784 Mar 20 06:01 com.plexapp.plugins.library.blobs.db
-rw-r--r-- 1 plex plex 32768 Apr 10 15:21 com.plexapp.plugins.library.blobs.db-shm
-rw-r--r-- 1 plex plex 128121176 Apr 10 02:03 com.plexapp.plugins.library.blobs.db-wal
-rwxrw-rw- 1 plex plex 906445824 Apr 10 23:11 com.plexapp.plugins.library.db
nas: ~# "/usr/local/share/plexmediaserver-plexpass/Plex SQLite" "/mnt/ssd/system/plexpass/plexdata/Plex Media Server/Plug-in Support/Databases/com.plexapp.plugins.library.db" "PRAGMA integrity_check"
ok
The result is the same
Apr 10, 2022 23:26:02.883 [0x8061ad100] INFO - Plex Media Server v1.25.6.5577-c8bd13540 - FreeBSD PC amd64 - build: freebsd-x86_64 freebsd - GMT 06:00
Apr 10, 2022 23:26:02.885 [0x8061ad100] INFO - FreeBSD version: 12.3-RELEASE, language: en-US
Apr 10, 2022 23:26:02.885 [0x8061ad100] WARN - Read of sysctl machdep.cpu.brand_string failed: 2 (No such file or directory)
Apr 10, 2022 23:26:02.885 [0x8061ad100] INFO - Processor: 4-core
Apr 10, 2022 23:26:02.885 [0x8061ad100] INFO - Compiler is - Clang 11.0.1 (https://plex.tv b587490162c22e078c314e3f7dc560c691d126aa)
Apr 10, 2022 23:26:02.886 [0x8061ad100] INFO - /usr/local/share/plexmediaserver-plexpass/Plex_Media_Server
Apr 10, 2022 23:26:02.885 [0x8060f3000] DEBUG - BPQ: [Idle] -> [Starting]
Apr 10, 2022 23:26:02.887 [0x8060f3000] DEBUG - FeatureManager: Using cached data for features list
Apr 10, 2022 23:26:02.890 [0x8060f3000] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.35.5, threadsafe=1
Apr 10, 2022 23:26:02.937 [0x8060f3000] DEBUG - Running migrations. (EPG 0)
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Running forward migration 202107070000.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 0.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 1.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 2.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 3.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 4.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 5.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 6.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 7.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 8.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 9.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 10.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 11.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 12.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 13.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 14.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 15.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 16.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 17.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 18.
Apr 10, 2022 23:26:02.938 [0x8060f3000] DEBUG - Captured session 19.
Apr 10, 2022 23:26:02.940 [0x8060f3000] DEBUG - Analyzing database.
Apr 10, 2022 23:26:28.215 [0x8060f3000] DEBUG - Vacuuming database.
Apr 10, 2022 23:26:44.174 [0x8060f3000] ERROR - SQLITE3:0x10, 13, statement aborts at 8: [INSERT INTO vacuum_db.'taggings' SELECT*FROM"main".'taggings'] database or disk is full
Apr 10, 2022 23:26:44.183 [0x8060f3000] ERROR - SQLITE3:0x10, 13, statement aborts at 1: [VACUUM] database or disk is full
Apr 10, 2022 23:26:44.204 [0x8060f3000] ERROR - Exception thrown during migrations, aborting: sqlite3_statement_backend::loadOne: database or disk is full
Apr 10, 2022 23:26:50.481 [0x8060f3000] DEBUG - ChangestampAllocator: initialized to 78467867
Apr 10, 2022 23:26:50.581 [0x8060f3000] DEBUG - Opening 2 database sessions to library (com.plexapp.plugins.library.blobs), SQLite 3.35.5, threadsafe=1
Apr 10, 2022 23:26:50.584 [0x8060f3000] INFO - SQLITE3:0x10, 283, recovered 171 frames from WAL file /mnt/ssd/system/plexpass/plexdata/Plex Media Server/Plug-in Support/Databases/com.plexapp.plugins.library.blobs.db-wal
Apr 10, 2022 23:26:50.589 [0x8060f3000] DEBUG - Running migrations. (EPG 0)
Apr 10, 2022 23:26:50.589 [0x8060f3000] DEBUG - Running forward migration 202107070000.
Apr 10, 2022 23:26:50.589 [0x8060f3000] DEBUG - Captured session 0.
Apr 10, 2022 23:26:50.589 [0x8060f3000] DEBUG - Captured session 1.
Apr 10, 2022 23:26:50.589 [0x8060f3000] DEBUG - Analyzing database.
Apr 10, 2022 23:26:51.247 [0x8060f3000] DEBUG - Vacuuming database.
Apr 10, 2022 23:26:52.978 [0x8060f3000] ERROR - SQLITE3:0x10, 13, statement aborts at 7: [INSERT INTO vacuum_db.'blobs' SELECT*FROM"main".'blobs'] database or disk is full
Apr 10, 2022 23:26:52.988 [0x8060f3000] ERROR - SQLITE3:0x10, 13, statement aborts at 1: [VACUUM] database or disk is full
Apr 10, 2022 23:26:52.991 [0x8060f3000] ERROR - Exception thrown during migrations, aborting: sqlite3_statement_backend::loadOne: database or disk is full
Apr 10, 2022 23:26:52.995 [0x807333100] INFO - CodecManager: obtaining EAE
Apr 10, 2022 23:26:52.996 [0x807333100] DEBUG - MyPlex: mapping state set to 'Unknown'.
Apr 10, 2022 23:26:52.997 [0x807333100] DEBUG - Relay: read 70 cached entries from hosts file
Apr 10, 2022 23:26:52.998 [0x807333100] DEBUG - HTTP requesting GET https://plex.tv/api/codecs/easyaudioencoder?build=freebsd-x86_64-standard&deviceId=e773a324-063d-4c7f-a249-b505a2a8695c&oldestPreviousVersion=1%2E15%2E5%2E994-4610c6e8d&version=1714
Apr 10, 2022 23:26:53.008 [0x8060f3000] DEBUG - [CERT] Subject name is /CN=*.33a5bf95d32e4e8a80440ac77869faae.plex.direct
Apr 10, 2022 23:26:53.008 [0x8060f3000] DEBUG - [CERT] Installed certificate with fingerprint 5e:f0:18:55:32:49:d4:08:cb:c8:6e:92:66:a9:4c:67:e8:e7:52:80.
Apr 10, 2022 23:26:53.008 [0x8060f3000] DEBUG - [CERT/OCSP] Stapling requests will be made to 'http://r3.o.lencr.org/'.
Apr 10, 2022 23:26:53.008 [0x8060f3000] INFO - [CERT/OCSP] Successfully retrieved response from cache.
This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.
