Current version: 2.0.0.4889 develop
Attempting to update to 2.0.0.4941 develop
Platform: Synology 1813+ / Mono 4.6.2
It fails after downloading and extracting the update. I’ve seen this exact error yesterday as well, with a maintenance release that’s no longer in the list (presumably because it’s rolled up in this release, I don’t know…).
This one pops out, because that’s what I also see when running at normal info level logging:
17-8-10 15:09:14.4|Error|CommandExecutor|Error occurred while executing task ApplicationUpdate
[v2.0.0.4889] System.Data.SQLite.SQLiteException (0x80004005): attempt to write a readonly database
not an error
at System.Data.SQLite.SQLite3.StepBackup (System.Data.SQLite.SQLiteBackup backup, System.Int32 nPage, System.Boolean& retry) [0x0007e] in <61a20cde294d4a3eb43b9d9f6284613b>:0
at System.Data.SQLite.SQLiteConnection.BackupDatabase (System.Data.SQLite.SQLiteConnection destination, System.String destinationName, System.String sourceName, System.Int32 pages, System.Data.SQLite.SQLiteBackupCallback callback, System.Int32 retryMilliseconds) [0x000b5] in <61a20cde294d4a3eb43b9d9f6284613b>:0
Trace log below:
17-8-10 15:09:09.1|Trace|CommandQueueManager|Publishing ApplicationUpdate
17-8-10 15:09:09.1|Trace|CommandQueueManager|Checking if command is queued or started: ApplicationUpdate
17-8-10 15:09:09.1|Trace|CommandQueueManager|Inserting new command: ApplicationUpdate
17-8-10 15:09:09.2|Trace|CommandExecutor|ApplicationUpdateCommand -> InstallUpdateService
17-8-10 15:09:09.2|Trace|Http|Res: 30 [POST] /api/command: 201.Created (146 ms)
17-8-10 15:09:09.2|Trace|CommandQueueManager|Marking command as started: ApplicationUpdate
17-8-10 15:09:09.2|Debug|Api|[POST] /api/command: 201.Created (146 ms)
17-8-10 15:09:09.3|Trace|EventAggregator|Publishing CommandUpdatedEvent
17-8-10 15:09:09.3|Trace|EventAggregator|CommandUpdatedEvent -> CommandModule
17-8-10 15:09:09.3|Trace|EventAggregator|CommandUpdatedEvent <- CommandModule
17-8-10 15:09:09.3|Debug|InstallUpdateService|Checking for updates
17-8-10 15:09:09.3|Trace|EventAggregator|Publishing CommandUpdatedEvent
17-8-10 15:09:09.3|Trace|EventAggregator|CommandUpdatedEvent -> CommandModule
17-8-10 15:09:09.3|Trace|EventAggregator|CommandUpdatedEvent <- CommandModule
17-8-10 15:09:09.3|Trace|HttpClient|Req: [GET] http://services.sonarr.tv/v1/update/develop?version=2.0.0.4889&os=linux&runtimeVer=4.6.2
17-8-10 15:09:09.3|Trace|ConfigService|Using default config value for 'proxyenabled' defaultValue:'False'
17-8-10 15:09:09.8|Trace|HttpClient|Res: [GET] http://services.sonarr.tv/v1/update/develop?version=2.0.0.4889&os=linux&runtimeVer=4.6.2: 200.OK (432 ms)
17-8-10 15:09:09.8|Info|InstallUpdateService|Deleting old update files
17-8-10 15:09:09.8|Info|InstallUpdateService|Downloading update 2.0.0.4941
17-8-10 15:09:09.8|Trace|EventAggregator|Publishing CommandUpdatedEvent
17-8-10 15:09:09.8|Trace|EventAggregator|CommandUpdatedEvent -> CommandModule
17-8-10 15:09:09.8|Trace|EventAggregator|CommandUpdatedEvent <- CommandModule
17-8-10 15:09:09.8|Debug|InstallUpdateService|Downloading update package from [http://download.sonarr.tv/v2/develop/mono/NzbDrone.develop.2.0.0.4941.mono.tar.gz] to [/tmp/nzbdrone_update/NzbDrone.develop.2.0.0.4941.mono.tar.gz]
17-8-10 15:09:09.8|Debug|HttpClient|Downloading [http://download.sonarr.tv/v2/develop/mono/NzbDrone.develop.2.0.0.4941.mono.tar.gz] to [/tmp/nzbdrone_update/NzbDrone.develop.2.0.0.4941.mono.tar.gz]
17-8-10 15:09:10.8|Debug|HttpClient|Downloading Completed. took 1s
17-8-10 15:09:10.8|Info|InstallUpdateService|Verifying update package
17-8-10 15:09:10.8|Trace|EventAggregator|Publishing CommandUpdatedEvent
17-8-10 15:09:10.8|Trace|EventAggregator|CommandUpdatedEvent -> CommandModule
17-8-10 15:09:10.8|Trace|EventAggregator|CommandUpdatedEvent <- CommandModule
17-8-10 15:09:11.7|Info|InstallUpdateService|Update package verified successfully
17-8-10 15:09:11.7|Info|InstallUpdateService|Extracting Update package
17-8-10 15:09:11.7|Trace|EventAggregator|Publishing CommandUpdatedEvent
17-8-10 15:09:11.7|Trace|EventAggregator|CommandUpdatedEvent -> CommandModule
17-8-10 15:09:11.7|Trace|EventAggregator|CommandUpdatedEvent <- CommandModule
17-8-10 15:09:11.7|Debug|ArchiveService|Extracting archive [/tmp/nzbdrone_update/NzbDrone.develop.2.0.0.4941.mono.tar.gz] to [/tmp/nzbdrone_update/]
17-8-10 15:09:13.6|Debug|ArchiveService|Extraction complete.
17-8-10 15:09:13.6|Info|InstallUpdateService|Update package extracted successfully
17-8-10 15:09:13.6|Info|BackupService|Starting Backup
17-8-10 15:09:13.6|Trace|EventAggregator|Publishing CommandUpdatedEvent
17-8-10 15:09:13.6|Trace|EventAggregator|CommandUpdatedEvent -> CommandModule
17-8-10 15:09:13.6|Trace|EventAggregator|CommandUpdatedEvent <- CommandModule
17-8-10 15:09:13.6|Trace|DiskProviderBase|Deleting file: /tmp/nzbdrone_backup/config.xml
17-8-10 15:09:13.6|Trace|DiskProviderBase|Deleting file: /tmp/nzbdrone_backup/nzbdrone.db
17-8-10 15:09:13.6|Debug|BackupService|Cleaning up old backup files
17-8-10 15:09:13.6|Debug|BackupService|Finished cleaning up old backup files
17-8-10 15:09:13.6|Debug|BackupService|Backing up config.xml
17-8-10 15:09:13.6|Trace|EventAggregator|Publishing CommandUpdatedEvent
17-8-10 15:09:13.6|Trace|EventAggregator|CommandUpdatedEvent -> CommandModule
17-8-10 15:09:13.6|Trace|EventAggregator|CommandUpdatedEvent <- CommandModule
17-8-10 15:09:13.6|Debug|DiskTransferService|Copy [/usr/local/nzbdrone/var/.config/NzbDrone/config.xml] > [/tmp/nzbdrone_backup/config.xml]
17-8-10 15:09:13.6|Trace|SymbolicLinkResolver|Checking path /tmp/nzbdrone_backup/config.xml for symlink returned error ENOENT, assuming it's not a symlink.
17-8-10 15:09:13.6|Debug|BackupService|Backing up database
17-8-10 15:09:13.6|Trace|EventAggregator|Publishing CommandUpdatedEvent
17-8-10 15:09:13.6|Trace|EventAggregator|CommandUpdatedEvent -> CommandModule
17-8-10 15:09:13.6|Trace|EventAggregator|CommandUpdatedEvent <- CommandModule
17-8-10 15:09:13.8|Trace|Owin|SQLite error (5): database is locked
17-8-10 15:09:13.8|Trace|Owin|SQLite error (5): database is locked
17-8-10 15:09:14.0|Trace|Owin|SQLite error (5): database is locked
17-8-10 15:09:14.1|Trace|CommandQueueManager|Updating command status
17-8-10 15:09:14.3|Trace|EventAggregator|Publishing CommandUpdatedEvent
17-8-10 15:09:14.3|Trace|EventAggregator|CommandUpdatedEvent -> CommandModule
17-8-10 15:09:14.3|Trace|EventAggregator|CommandUpdatedEvent <- CommandModule
17-8-10 15:09:14.3|Trace|EventAggregator|Publishing CommandExecutedEvent
17-8-10 15:09:14.3|Trace|EventAggregator|CommandExecutedEvent -> TaskManager
17-8-10 15:09:14.3|Trace|TaskManager|Updating last run time for: NzbDrone.Core.Update.Commands.ApplicationUpdateCommand
17-8-10 15:09:14.4|Trace|EventAggregator|CommandExecutedEvent <- TaskManager
17-8-10 15:09:14.4|Trace|EventAggregator|CommandExecutedEvent -> TaskModule
17-8-10 15:09:14.4|Trace|EventAggregator|CommandExecutedEvent <- TaskModule
17-8-10 15:09:14.4|Error|CommandExecutor|Error occurred while executing task ApplicationUpdate
[v2.0.0.4889] System.Data.SQLite.SQLiteException (0x80004005): attempt to write a readonly database
not an error
at System.Data.SQLite.SQLite3.StepBackup (System.Data.SQLite.SQLiteBackup backup, System.Int32 nPage, System.Boolean& retry) [0x0007e] in <61a20cde294d4a3eb43b9d9f6284613b>:0
at System.Data.SQLite.SQLiteConnection.BackupDatabase (System.Data.SQLite.SQLiteConnection destination, System.String destinationName, System.String sourceName, System.Int32 pages, System.Data.SQLite.SQLiteBackupCallback callback, System.Int32 retryMilliseconds) [0x000b5] in <61a20cde294d4a3eb43b9d9f6284613b>:0
17-8-10 15:09:20.8|Trace|Http|Req: 31 [GET] /api/log/file
17-8-10 15:09:20.8|Trace|Http|Res: 31 [GET] /api/log/file: 200.OK (11 ms)
