Unable to connect to Plex after upgrade

Plex Media Server NAS & Devices
1

Server Version#: 1.26.0.5715
Player Version#:

I attempted to upgrade on my Synology DS 218+ to version 1.26.0.5715. The upgrade was successful and the server starts. When I attempt to connect to plex, I get an error in my browser that I cannot connect to the server. I see the following in my logs.

Apr 20, 2022 11:12:34.648 [0x7fea4f860b38] INFO - Plex Media Server v1.26.0.5715-8cf78dab3 - Synology DS218+ x86_64 - build: linux-x86_64 synology - GMT -04:00
Apr 20, 2022 11:12:34.649 [0x7fea4f860b38] INFO - Linux version: DSM 6.2.4.25556-3, language: en-US
Apr 20, 2022 11:12:34.649 [0x7fea4f860b38] INFO - Processor: 2-core Intel(R) Celeron(R) CPU J3355 @ 2.00GHz
Apr 20, 2022 11:12:34.649 [0x7fea4f860b38] INFO - Compiler is - Clang 11.0.1 (https://plex.tv b587490162c22e078c314e3f7dc560c691d126aa)
Apr 20, 2022 11:12:34.649 [0x7fea4f860b38] INFO - /var/packages/Plex Media Server/target/Plex Media Server
Apr 20, 2022 11:12:34.735 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 283, recovered 664 frames from WAL file /volume1/Plex/Library/Application Support/Plex Media Server/Plug-in Support/Databases/com.plexapp.plugins.library.db-wal
Apr 20, 2022 11:12:34.946 [0x7fea52f06b58] WARN - Held transaction for too long (/data/jenkins/server/3529576666/Library/DatabaseMigrations.cpp:237): 0.130000 seconds
Apr 20, 2022 11:12:36.075 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.078 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.082 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.085 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.089 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.093 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.097 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.102 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.105 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.109 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.112 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.116 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.119 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.123 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.126 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.130 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.133 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.137 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.141 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Apr 20, 2022 11:12:36.383 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 7: [SELECT value FROM 'main'.'fts4_metadata_titles_stat' WHERE id=?] database schema has changed
Apr 20, 2022 11:12:36.910 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 24: [SELECT (SELECT max(idx) FROM 'main'.'fts4_tag_titles_segdir' WHERE level = ?) + 1] database schema has changed
Apr 20, 2022 11:12:36.910 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 22: [SELECT coalesce((SELECT max(blockid) FROM 'main'.'fts4_tag_titles_segments') + 1, 1)] database schema has changed
Apr 20, 2022 11:12:36.910 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 13: [REPLACE INTO 'main'.'fts4_tag_titles_segments'(blockid, block) VALUES(?, ?)] database schema has changed
Apr 20, 2022 11:12:36.924 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 25: [REPLACE INTO 'main'.'fts4_tag_titles_segdir' VALUES(?,?,?,?,?,?)] database schema has changed
Apr 20, 2022 11:12:36.924 [0x7fea52f06b58] INFO - SQLITE3:0x80000001, 17, statement aborts at 39: [SELECT level, idx, end_block FROM 'main'.'fts4_tag_titles_segdir' WHERE level BETWEEN ? AND ? ORDER BY level DESC, idx ASC] database schema has changed
Apr 20, 2022 11:12:42.049 [0x7fea52f06b58] WARN - Held transaction for too long (/data/jenkins/server/3529576666/Library/FullTextSearch.cpp:58): 3.680000 seconds
Apr 20, 2022 11:12:43.433 [0x7fea52f06b58] WARN - Held transaction for too long (/data/jenkins/server/3529576666/Library/FullTextSearch.cpp:71): 0.950000 seconds
Apr 20, 2022 11:12:48.442 [0x7fea52f06b58] WARN - Held transaction for too long (/data/jenkins/server/3529576666/Library/FullTextSearch.cpp:81): 3.810000 seconds
Apr 20, 2022 11:12:50.919 [0x7fea52f06b58] WARN - Held transaction for too long (/data/jenkins/server/3529576666/Library/FullTextSearch.cpp:94): 2.230000 seconds
Apr 20, 2022 11:12:50.920 [0x7fea52f06b58] WARN - Held transaction for too long (/data/jenkins/server/3529576666/Library/DatabaseMigrations.cpp:237): 11.410000 seconds
2

Depending on which version you upgraded from, it may take several minutes for the server to become ready.

There was a major database change which requires several minutes of processing before PMS becomes ready.

Judging by that snippet, it looks like your DB is indeed being upgraded.
(the DEBUG logs, and ZIP file, would have been much more helpful. FYI. You don’t save any space by turning off DEBUG logging)

What is the CPU load?

I ask because, while upgrading, you’ll see higher startup CPU load.
When the upgrade is complete, it’ll return to normal / near idle usage.

3

The CPU is averaging around 40% but spiking at 95% plus. The Task Manager shows that Plex Server is taking the majority of the CPU so I would assume that it is still performing the upgrade.

It has been running for over an hour, so I don’t know how long I should wait. Does it depend on the size of my library? I have about 11 TB of content.

Am I able to turn on DEBUG logs without the server being accessible? Also, where is the ZIP file located? I only see other lugs for CRASH, DNLA, MEDIA SCANNER, TUNER SERVICE.

I will gladly send logs, but just need to know where they are located.

4

The amount of storage you’re using isn’t as significant as the item count.

If you have a lot of music or photos, which don’t take up much space compared to moviies/episodes, then taking this time is very much expected.

The ARMv8 CPU in your NAS is a good ARM CPU. I have one here as my development system.

As part of this upgrade, are you upgrading from a version lower than 1.24.2.4973 ?
If so, you’ll also see the packaging doing its work too. That package work, while not hard on the CPU, is seen when installing / upgrading. ( It will sit at “Installing” or “processing” while the package completes that task. This is usually very low CPU usage )

At this point, please let it complete.

When it’s done and you can again log in normally, I do request you download the logs and attach here so we can confirm everything went OK.

5

The Plex server finally started up and I am able to access my content. Please advise which logs you would like me to attach and how to best download them.

Thanks for your help.

6

Settings - Server - Troubleshooting (lower left corner) - Download Logs.

It will give you a ZIP file.

Please attach that here.

7

Logs attached
Plex Media Server Logs_2022-04-22_15-10-50.zip (3.2 MB)

8

Thanks for the logs.

By getting them, at least you’re able to confirm PMS is running and accessible via the web client.

Now for the bad news.

  1. You turned off DEBUG logging. I can’t see anything. (Turning DEBUG off does not save any space. Logs are fixed at 10.5 MB each)

  2. This looks like bad media but I can’t tell because all the pre-play DEBUG is missing.

Apr 22, 2022 03:41:12.271 [0x7fea4f860b38] INFO - Compiler is - Clang 11.0.1 (https://plex.tv b587490162c22e078c314e3f7dc560c691d126aa)
Apr 22, 2022 03:41:12.271 [0x7fea4f860b38] INFO - /var/packages/Plex Media Server/target/Plex Media Server
Apr 22, 2022 03:41:12.272 [0x7fea46d2eb38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.272 [0x7fea46badb38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] decode_band_types: Input buffer exhausted before END element found
Apr 22, 2022 03:41:12.272 [0x7fea46c08b38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.273 [0x7fea46d2eb38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] decode_band_types: Input buffer exhausted before END element found
Apr 22, 2022 03:41:12.273 [0x7fea46badb38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.273 [0x7fea46c08b38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] decode_band_types: Input buffer exhausted before END element found
Apr 22, 2022 03:41:12.273 [0x7fea46d2eb38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.273 [0x7fea46badb38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] decode_band_types: Input buffer exhausted before END element found
Apr 22, 2022 03:41:12.274 [0x7fea46c08b38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.274 [0x7fea46d2eb38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] decode_band_types: Input buffer exhausted before END element found
Apr 22, 2022 03:41:12.274 [0x7fea46badb38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.274 [0x7fea46c08b38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] decode_band_types: Input buffer exhausted before END element found
Apr 22, 2022 03:41:12.275 [0x7fea46d2eb38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.275 [0x7fea46badb38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] Number of bands (56) exceeds limit (46).
Apr 22, 2022 03:41:12.275 [0x7fea46c08b38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.276 [0x7fea46d2eb38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] decode_band_types: Input buffer exhausted before END element found
Apr 22, 2022 03:41:12.276 [0x7fea46badb38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.276 [0x7fea46c08b38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] decode_band_types: Input buffer exhausted before END element found
Apr 22, 2022 03:41:12.276 [0x7fea46d2eb38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.277 [0x7fea46badb38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] decode_band_types: Input buffer exhausted before END element found
Apr 22, 2022 03:41:12.277 [0x7fea46c08b38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.277 [0x7fea46d2eb38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] decode_band_types: Input buffer exhausted before END element found
Apr 22, 2022 03:41:12.277 [0x7fea46badb38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.277 [0x7fea46c08b38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] decode_band_types: Input buffer exhausted before END element found
Apr 22, 2022 03:41:12.278 [0x7fea46d2eb38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.278 [0x7fea46badb38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] decode_band_types: Input buffer exhausted before END element found
Apr 22, 2022 03:41:12.278 [0x7fea46c08b38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.278 [0x7fea46d2eb38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] decode_band_types: Input buffer exhausted before END element found
Apr 22, 2022 03:41:12.278 [0x7fea46badb38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.279 [0x7fea46c08b38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] invalid band type
Apr 22, 2022 03:41:12.280 [0x7fea46d2eb38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.280 [0x7fea46badb38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] channel element 3.15 is not allocated
Apr 22, 2022 03:41:12.280 [0x7fea46c08b38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.280 [0x7fea46d2eb38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] decode_band_types: Input buffer exhausted before END element found
Apr 22, 2022 03:41:12.280 [0x7fea46badb38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.281 [0x7fea46c08b38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] Number of bands (47) exceeds limit (46).
Apr 22, 2022 03:41:12.281 [0x7fea46d2eb38] ERROR - [Transcoder] Error while decoding stream #0:1: Invalid data found when processing input
Apr 22, 2022 03:41:12.281 [0x7fea46badb38] ERROR - [Transcoder] [aac @ 0x7efd2b384240] invalid band type
9

Greetings-
I just had this same experience. Is there anyway this could be added as a Warning in the Release Notes?

10

The database changes which impact startup were announced here:

11

2 posts were split to a new topic: Unable to access Plex after upgrade

12

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