Can't update Prowlarr using WebUI about dietpi

[MichaIng]

Actually the shutdown and updater part of the logs look exactly the same. Only on restart the correct new version is restarted. So why it was the old version, without any error message from the updater, will remain a mystery. But maybe there were errors done by the Prowlarr.Update process, just not visible in the journal. Not sure whether it logs to prowlarr.txt, but as this is a RAM log, those would be gone.

I remember we had a similar issue reported with another Servarr server. I'll keep this in mind, and when such happens again for someone, we need to check how to find logs from the dedicated updater process. Because it must have failed to replace the files, otherwise it would be impossible for the old version to start.

[MichaIng]

Hmm, the logs look okay: The updater kills the main process, systemd leaves the updater alive and just restarts the main process. Based on the 42 seconds between "Prowlarr will restart shortly." and main process kill I would say the updater finished, and itself killed Prowlarr once done. But indeed the same old v1.12 starts 🤔.

Can you check journalctl and /var/log/prowlarr whether there are logs from the updater process, at best with timestamps to compare/complement with Prowlarr service logs?

And lets also check whether the systemd service really is the latest one we ship:

systemctl cat prowlarr

[gioxx]

Ciao @MichaIng and thanks.
I tested again today after upgrading to DietPi 9.1.1 and this time everything went smoothly. I cannot explain why but the procedure performed was the same as yesterday, this time, however, something has evidently changed:

Feb 20 11:48:59 Jackett Prowlarr[26050]: [Info] InstallUpdateService: Downloading update 1.13.3.4273
Feb 20 11:49:02 Jackett Prowlarr[26050]: [Info] InstallUpdateService: Verifying update package
Feb 20 11:49:03 Jackett Prowlarr[26050]: [Info] InstallUpdateService: Update package verified successfully
Feb 20 11:49:03 Jackett Prowlarr[26050]: [Info] InstallUpdateService: Extracting Update package
Feb 20 11:49:04 Jackett Prowlarr[26050]: [Info] InstallUpdateService: Update package extracted successfully
Feb 20 11:49:04 Jackett Prowlarr[26050]: [Info] BackupService: Starting Backup
Feb 20 11:49:06 Jackett Prowlarr[26050]: [Info] InstallUpdateService: Preparing client
Feb 20 11:49:18 Jackett Prowlarr[26050]: [Info] InstallUpdateService: Starting update client /tmp/prowlarr_update/Prowlarr.Update
Feb 20 11:49:18 Jackett Prowlarr[26050]: [Info] InstallUpdateService: Prowlarr will restart shortly.
Feb 20 11:50:26 Jackett systemd[1]: prowlarr.service: Main process exited, code=killed, status=9/KILL
Feb 20 11:50:26 Jackett systemd[1]: prowlarr.service: Failed with result 'signal'.
Feb 20 11:50:26 Jackett systemd[1]: prowlarr.service: Unit process 28006 (Prowlarr.Update) remains running after unit stopped.
Feb 20 11:50:26 Jackett systemd[1]: prowlarr.service: Consumed 37.704s CPU time.
Feb 20 11:50:26 Jackett systemd[1]: prowlarr.service: Scheduled restart job, restart counter is at 1.
Feb 20 11:50:26 Jackett systemd[1]: Stopped Prowlarr (DietPi).
Feb 20 11:50:26 Jackett systemd[1]: prowlarr.service: Consumed 37.721s CPU time.
Feb 20 11:50:26 Jackett systemd[1]: prowlarr.service: Found left-over process 28006 (Prowlarr.Update) in control group while starting unit. Ignoring.
Feb 20 11:50:26 Jackett systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies.
Feb 20 11:50:26 Jackett systemd[1]: Started Prowlarr (DietPi).
Feb 20 11:50:26 Jackett Prowlarr[28048]: [Info] Bootstrap: Starting Prowlarr - /opt/prowlarr/Prowlarr - Version 1.13.3.4273
Feb 20 11:50:26 Jackett Prowlarr[28048]: [Info] AppFolderInfo: Data directory is being overridden to [/mnt/dietpi_userdata/prowlarr]
Feb 20 11:50:26 Jackett Prowlarr[28048]: [Debug] Bootstrap: Console selected
Feb 20 11:50:26 Jackett Prowlarr[28048]: [Info] AppFolderInfo: Data directory is being overridden to [/mnt/dietpi_userdata/prowlarr]
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] AppFolderInfo: Data directory is being overridden to [/mnt/dietpi_userdata/prowlarr]
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] MigrationController: *** Migrating data source=/mnt/dietpi_userdata/prowlarr/prowlarr.db;cache size=-20000;datetimekind=Utc;journal mode=Wal;pooling=True;version=3;busytimeout=100 ***
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: DatabaseEngineVersionCheck migrating
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: PerformDBOperation
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: Performing DB Operation
Feb 20 11:50:27 Jackett Prowlarr[28048]: SQLite notice (283): recovered 1 frames from WAL file /mnt/dietpi_userdata/prowlarr/prowlarr.db-wal
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] DatabaseEngineVersionCheck: SQLite 3.34.1
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: => 0.0321526s
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: DatabaseEngineVersionCheck migrated
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: => 0.0334971s
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: 39: email_encryption migrating
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: Beginning Transaction
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] email_encryption: Starting migration of Main DB to 39
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: PerformDBOperation
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: Performing DB Operation
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: => 0.0053188s
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: INSERT INTO "VersionInfo" ("Version", "AppliedOn", "Description") VALUES (39, '2024-02-20T10:50:27', 'email_encryption')
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: Committing Transaction
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: 39: email_encryption migrated
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: => 0.0287963s
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Debug] MigrationController: Took: 00:00:00.1615278
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] MigrationController: *** Migrating data source=/mnt/dietpi_userdata/prowlarr/logs.db;cache size=-20000;datetimekind=Utc;journal mode=Wal;pooling=True;version=3;busytimeout=100 ***
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: DatabaseEngineVersionCheck migrating
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: PerformDBOperation
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: Performing DB Operation
Feb 20 11:50:27 Jackett Prowlarr[28048]: SQLite notice (283): recovered 6 frames from WAL file /var/log/prowlarr/logs.db-wal
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] DatabaseEngineVersionCheck: SQLite 3.34.1
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: => 0.0007928s
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: DatabaseEngineVersionCheck migrated
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: => 0.0009118s
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: 39: email_encryption migrating
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: Beginning Transaction
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] email_encryption: Starting migration of Log DB to 39
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: INSERT INTO "VersionInfo" ("Version", "AppliedOn", "Description") VALUES (39, '2024-02-20T10:50:27', 'email_encryption')
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] NzbDrone.Core.Datastore.Migration.Framework.NzbDroneSQLiteProcessor: Committing Transaction
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: 39: email_encryption migrated
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] FluentMigrator.Runner.MigrationRunner: => 0.0009447s
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] Microsoft.Hosting.Lifetime: Now listening on: http://[::]:9696
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] CommandExecutor: Starting 3 threads for tasks.
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] Microsoft.Hosting.Lifetime: Application started. Press Ctrl+C to shut down.
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] Microsoft.Hosting.Lifetime: Hosting environment: Production
Feb 20 11:50:27 Jackett Prowlarr[28048]: [Info] Microsoft.Hosting.Lifetime: Content root path: /

During the upgrade process:

dietpi@Jackett:/var/log/prowlarr# ls -la /var/log/prowlarr/
total 752
drwxr-xr-x 2 prowlarr dietpi    120 Feb 20 11:49 .
drwxr-xr-x 9 root     root      280 Jan 20 19:27 ..
-rw-r--r-- 1 prowlarr dietpi 700416 Feb 20 11:49 logs.db
-rw-r--r-- 1 prowlarr dietpi  32768 Feb 20 11:49 logs.db-shm
-rw-r--r-- 1 prowlarr dietpi  24752 Feb 20 11:49 logs.db-wal
-rw-rw-r-- 1 prowlarr dietpi   4237 Feb 20 11:49 prowlarr.txt

Yesterday I had also tried rebooting the VM to get a clean start but evidently that was not enough. No matter what, in the end the upgrade was successful and that is the important thing.
Thank you again for your support, I will close the issue directly.