UI says "Starting Backup" even though backup has completed

[Curtis_Klope]

The console says that it’s still Starting Backup even though you can see that a backup has been completed already:

Also, for the entire time since it started and until now, the TrayIcon process is consuming a lot of CPU and Disk, so it seems like it’s still doing something…

Log is here:

DeletedFiles: 22
DeletedFolders: 0
ModifiedFiles: 22
ExaminedFiles: 97230
OpenedFiles: 29
AddedFiles: 7
SizeOfModifiedFiles: 211230244
SizeOfAddedFiles: 20621
SizeOfExaminedFiles: 93636114604
SizeOfOpenedFiles: 211251045
NotProcessedFiles: 0
AddedFolders: 0
TooLargeFiles: 0
FilesWithError: 0
ModifiedFolders: 0
ModifiedSymlinks: 0
AddedSymlinks: 0
DeletedSymlinks: 0
PartialBackup: False
Dryrun: False
MainOperation: Backup
CompactResults: null
DeleteResults:
    DeletedSets: []
    Dryrun: False
    MainOperation: Delete
    CompactResults: null
    ParsedResult: Success
    Version: 2.0.4.5 (2.0.4.5_beta_2018-11-28)
    EndTime: 12/14/2018 3:19:28 PM (1544818768)
    BeginTime: 12/14/2018 3:18:03 PM (1544818683)
    Duration: 00:01:24.7910826
    Messages: [
        2018-12-14 14:45:34 -05 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: The operation Backup has started,
        2018-12-14 15:15:40 -05 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started:  (),
        2018-12-14 15:16:09 -05 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed:  (12.16 KB),
        2018-12-14 15:17:53 -05 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-b1bda7ad8c88f4bdbbc9614f4aa9d3d28.dblock.zip.aes (18.63 MB),
        2018-12-14 15:17:57 -05 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-b1bda7ad8c88f4bdbbc9614f4aa9d3d28.dblock.zip.aes (18.63 MB),
...
    ]
    Warnings: []
    Errors: []
    BackendStatistics:
        RemoteCalls: 8
        BytesUploaded: 28164967
        BytesDownloaded: 61079447
        FilesUploaded: 3
        FilesDownloaded: 3
        FilesDeleted: 0
        FoldersCreated: 0
        RetryAttempts: 0
        UnknownFileSize: 0
        UnknownFileCount: 0
        KnownFileCount: 12453
        KnownFileSize: 308895257041
        LastBackupDate: 12/14/2018 2:45:34 PM (1544816734)
        BackupListCount: 897
        TotalQuotaSpace: 3000249008128
        FreeQuotaSpace: 278977777664
        AssignedQuotaSpace: -1
        ReportedQuotaError: False
        ReportedQuotaWarning: False
        ParsedResult: Success
        Version: 2.0.4.5 (2.0.4.5_beta_2018-11-28)
        Messages: [
            2018-12-14 14:45:34 -05 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: The operation Backup has started,
            2018-12-14 15:15:40 -05 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started:  (),
            2018-12-14 15:16:09 -05 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed:  (12.16 KB),
            2018-12-14 15:17:53 -05 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-b1bda7ad8c88f4bdbbc9614f4aa9d3d28.dblock.zip.aes (18.63 MB),
            2018-12-14 15:17:57 -05 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-b1bda7ad8c88f4bdbbc9614f4aa9d3d28.dblock.zip.aes (18.63 MB),
...
        ]
        Warnings: []
        Errors: []
RepairResults: null
TestResults:
    MainOperation: Test
    Verifications: [
        Key: duplicati-20181212T162845Z.dlist.zip.aes
        Value: [],
        Key: duplicati-if08dddda965b45319892c3f9d858fbfb.dindex.zip.aes
        Value: [],
        Key: duplicati-b2819e8406f314ec79fe6e42217da5578.dblock.zip.aes
        Value: []
    ]
    ParsedResult: Success
    Version: 2.0.4.5 (2.0.4.5_beta_2018-11-28)
    EndTime: 12/14/2018 3:19:54 PM (1544818794)
    BeginTime: 12/14/2018 3:19:47 PM (1544818787)
    Duration: 00:00:07.2712318
    Messages: [
        2018-12-14 14:45:34 -05 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: The operation Backup has started,
        2018-12-14 15:15:40 -05 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started:  (),
        2018-12-14 15:16:09 -05 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed:  (12.16 KB),
        2018-12-14 15:17:53 -05 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-b1bda7ad8c88f4bdbbc9614f4aa9d3d28.dblock.zip.aes (18.63 MB),
        2018-12-14 15:17:57 -05 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-b1bda7ad8c88f4bdbbc9614f4aa9d3d28.dblock.zip.aes (18.63 MB),
...
    ]
    Warnings: []
    Errors: []
ParsedResult: Success
Version: 2.0.4.5 (2.0.4.5_beta_2018-11-28)
EndTime: 12/14/2018 3:19:54 PM (1544818794)
BeginTime: 12/14/2018 2:45:34 PM (1544816734)
Duration: 00:34:19.9740862
Messages: [
    2018-12-14 14:45:34 -05 - [Information-Duplicati.Library.Main.Controller-StartingOperation]: The operation Backup has started,
    2018-12-14 15:15:40 -05 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Started:  (),
    2018-12-14 15:16:09 -05 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: List - Completed:  (12.16 KB),
    2018-12-14 15:17:53 -05 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Started: duplicati-b1bda7ad8c88f4bdbbc9614f4aa9d3d28.dblock.zip.aes (18.63 MB),
    2018-12-14 15:17:57 -05 - [Information-Duplicati.Library.Main.BasicResults-BackendEvent]: Backend event: Put - Completed: duplicati-b1bda7ad8c88f4bdbbc9614f4aa9d3d28.dblock.zip.aes (18.63 MB),
...
]
Warnings: []
Errors: []

[JonMikelV]

Hi @Curtis_Klope, welcome to the forum!

Hasb always been happening or is it a recent occurrence?

It’s possible the 3:19 run ran into the start time of the 3:46 run so another run started right after the first ended.

If you look at the bottom of the System Info page there is a “lastPgEvent” line which should show what Duplicati is actually doing. If it’s empty, then you’ve found a UI bug.

[Curtis_Klope]

No, it will site like this for hours if I leave it. It doesn’t seem to ever show that it’s truly started, or finished. The UI will just say “Starting backup” forever until I force kill the process.

I noticed another thread that I think is describing a similar issue:

'Stop Backup' doesn't work

I’m trying to restore something from my backup, but as soon as I open the UI, the progress indicator says “Starting Backup.” So the database is locked, and I can’t do anything. I’ve tried ‘Stop after upload’ and ‘stop now,’ but neither one does a thing. How do I stop the backup so I can restore something? running Duplicati - 2.0.4.5_beta_2018-11-28 on Linux Mint 17.1: [image] lastEventId : 26 lastDataUpdateId : 11 lastNotificationUpdateId : 4 estimatedPauseEnd : 0001-01-01T00:00:00 act…

[Curtis_Klope]

Ugh, well, now it seems like its fine. Strange.