Skip to main content

UI shows error; MVP Log Viewer Does Not

Thread needs solution

Usually I just check the MVP Log Viewer to see if my backup finished normally. And it almost always shows no errors.

About a week ago, I happened to check the UI, which showed and error (the red circle with an X between Source and Destination) but, when I clicked on the X, the error was not specific -- just "there was an error".

Last night a full backup was done (I do incremental backups with occasional full backups and deletion of the old chains.

This morning I ran a validation.

The MVP Log Viewer shows no errors:

log uuid=48189A92-513E-4F3B-927B-ED5EB05CCA17; product=True Image; version=19.0; build=6595; task=B7465E88-7231-4F54-BACE-7CDD5FE5CA1A; task_name=Backup validation
Start= 1/21/2020 10:00:32 AM
id=1; level=2; module=100; code=2; date/time=1/21/2020 10:00:32 AM
message=Operation Backup validation started manually.
id=2; level=2; module=100; code=11; date/time=1/21/2020 10:00:35 AM
message=Priority changed to Low.
id=3; level=2; module=11; code=1026; date/time=1/21/2020 10:00:35 AM
message=Validate Backup Archive Location: L:\Dodier\My files_full_b16_s1_v1.tib
id=4; level=2; module=316; code=6; date/time=1/21/2020 11:25:44 AM
message=Operation has succeeded.
Start= 1/21/2020 10:00:32 AM
Finish= 1/21/2020 11:25:44 AM
Total Time= 01:25:12

The UI shows that the backup is corrupted.

Why this discrepancy?

0 Users found this helpful

Ron, sorry but no idea why the log shows successful and the UI says otherwise!

Please try doing a validation for this backup task and see if that corrects the UI status?

Steve,

I did that with the same result -- UI says backup is corrupt; MVP Log Viewer shows no errors.

Then I set up a new backup task; ran through the same process; and got the same results after both the original backup task; and the subsequent attempt to "verify".

I've been using this program for many years, and never had a problem like this.

 

Ron, can you post a copy of the log using the Log view: Regular option (rather than Short).

I would recommend running a CHKDSK for your destination drive.

Here's the log.  I don't see a toggle Regular/Short view though:

log uuid=AF9FF1C9-C2B0-4C17-AD6B-87A9A6EEF470;  product=True Image;  version=19.0;  build=6595;  task=9ECD9925-FAA8-4DCC-B73C-BE6F50387D3F;  task_name=Backup validation
Start= 1/22/2020 7:33:19 AM
id=1;  level=2;  module=100;  code=2;  date/time=1/22/2020 7:33:19 AM
          message=Operation Backup validation started manually.
id=2;  level=2;  module=100;  code=11;  date/time=1/22/2020 7:33:21 AM
          message=Priority changed to Low.
id=3;  level=2;  module=11;  code=1026;  date/time=1/22/2020 7:33:22 AM
          message=Validate Backup Archive Location: L:\Dodier2\My files2_full_b1_s1_v1.tib  
id=4;  level=2;  module=316;  code=6;  date/time=1/22/2020 8:48:00 AM
          message=Operation has succeeded.
Start= 1/22/2020 7:33:19 AM
Finish= 1/22/2020 8:48:00 AM
Total Time= 01:14:41

Here's the associated UI picture:

 

CHKDSK did find an error:

Usn Journal verification completed.
CHKDSK discovered free space marked as allocated in the
master file table (MFT) bitmap.
CHKDSK discovered free space marked as allocated in the volume bitmap.

Windows has made corrections to the file system.
No further action is required.

 I would not have thought that would cause a backup verification problem, though.

As always, your thoughts and suggestions are much appreciated.

Ron

And after the CHKDSK repair, same problem persists.

Here is the log.  The UI error message reads "The Last Backup has failed"

Note that I have also had this issue even if I did not do a verify after the full backup

 

log uuid=4327EE99-53B5-4515-AE83-62B1F3ADB723;  product=True Image;  version=19.0;  build=6595;  task=CEB3695C-4CBC-40DC-A18E-AE349165EFA8;  task_name=My files2
Start= 1/22/2020 9:58:34 PM
id=1;  level=2;  module=100;  code=0;  date/time=1/22/2020 9:58:34 PM
          message=Backup reserve copy attributes: format tib; need_reserve_backup_copy false
id=2;  level=2;  module=100;  code=2;  date/time=1/22/2020 9:58:34 PM
          message=Operation My files2 started by schedule.
id=3;  level=2;  module=100;  code=0;  date/time=1/22/2020 9:58:36 PM
          message=Backup reserve copy attributes: format tib; need_reserve_backup_copy false
id=4;  level=2;  module=316;  code=0;  date/time=1/22/2020 9:58:36 PM
          message=Operation: Backup
id=5;  level=2;  module=11;  code=1008;  date/time=1/22/2020 9:58:50 PM
          message=Create Backup Archive From: D:\Users\Ron\AppData\Local\Microsoft\Outlook\Archive.pst D:\Users\Ron\AppData\Local\Microsoft\Outlook\Outlook.pst D:\Users\Ron\Documents\ D:\Users\Ron\Downloads\ D:\Users\Ron\Music\ D:\Users\Ron\Pictures\ D:\Users\Ron\Videos\ D:\Chain2Gen\  To file: My files2_full_b1_s1_v1.tib Compression: Maximum Exclude: System files, Files matching mask Match criterion:
          hiberfil.sys
          pagefile.sys
          $Recycle.Bin
          swapfile.sys
          System Volume Information
          *.tib
          *.tib.metadata
          *.~
          *.tmp
          *.db3-journal
          d:\Users\Ron\AppData\Local\Temp
          d:\Users\DefaultAppPool\AppData\Local\Temp
          d:\Users\Ron\AppData\Local\Microsoft\Windows\INetCache
          d:\Users\DefaultAppPool\AppData\Local\Microsoft\Windows\INetCache
          d:\Users\Ron\AppData\Local\Google\Chrome\User Data
          d:\Users\Ron\AppData\Local\Opera Software
          d:\Users\Ron\AppData\Roaming\Opera\Opera
          d:\Users\Ron\AppData\Local\Mozilla\Firefox\Profiles
          d:\Users\DefaultAppPool\AppData\Local\Google\Chrome\User Data
          d:\Users\DefaultAppPool\AppData\Local\Opera Software
          d:\Users\DefaultAppPool\AppData\Roaming\Opera\Opera
          d:\Users\DefaultAppPool\AppData\Local\Mozilla\Firefox\Profiles
          C:\WINDOWS\CSC

id=6;  level=2;  module=100;  code=0;  date/time=1/22/2020 9:58:57 PM
          message=Writing full version to file: My files2_full_b1_s1_v1.tib
id=7;  level=2;  module=100;  code=0;  date/time=1/23/2020 12:18:23 AM
          message=The following backups have been successfully created: L:\Dodier2\My files2_full_b1_s1_v1.tib
id=8;  level=2;  module=11;  code=1026;  date/time=1/23/2020 12:18:24 AM
          message=Validate Backup Archive Location: L:\Dodier2\My files2_full_b1_s1_v1.tib  
id=9;  level=2;  module=316;  code=6;  date/time=1/23/2020 1:34:36 AM
          message=Operation has succeeded.
Start= 1/22/2020 9:58:34 PM
Finish= 1/23/2020 1:34:36 AM
Total Time= 03:36:02

Ron, given that the logs consistently say that both the backup and validation are successful, and you have fixed the free space issue via running CHKDSK, the I would recommend doing a repair install of ATI to see if that will resolve this issue with the GUI status?

See KB 60915: Acronis True Image: repairing program settings - for details.

Steve,

This morning, I did a

 - Clean boot (disabling non-MS services and all startup programs in msconfig/task manager
 - Uninstall and cleanup using the cleanup tool
 - Reinstall (and it activated automagically)
 - Re-enabled the startup programs and services
 - Created a new task

Finally, all is working.

I don't know if a Repair Install would have worked, but this new clean installation seems to be working OK except for an issue with email notifications, but I can live without them.  (That started when my email provider began requiring SSL/TLS and only occurs on this machine. It does not occur on another machine where I have Acronis).  I've tried the recommended fixes to no avail.

Maybe one of these days I'll update, but, so far, no good reason to do so.

Thanks for your help. You've been a great resource on this forum over the years.

Ron