Aller au contenu principal

2 PC's, W10, gone for a week and backup scheduled weekly.

Thread needs solution

We'll both PC's should have run a backup today when turned on. Both were off for a full week when we were away. However, one DID NOT?

Wife's was scheduled to run on Tues. day and today it did run. Her version is W10 Pro.

Mine was scheduled to run Sat., but it DID NOT run today? I opened ATI 2016 and the last was 7/22 and the next scheduled for 8/5. I looked at the log files folder, did NOT even run with an error?

====

05/20/2017  02:36 PM             7,244 service_2017-05-20-14-01-02.log
05/27/2017  03:35 PM             7,244 service_2017-05-27-15-05-33.log
06/03/2017  02:30 PM             7,244 service_2017-06-03-14-01-01.log
06/10/2017  02:36 PM             7,244 service_2017-06-10-14-06-03.log
06/17/2017  02:37 PM             7,244 service_2017-06-17-14-06-02.log
06/24/2017  03:47 PM             7,244 service_2017-06-24-15-17-02.log
07/01/2017  02:35 PM             7,244 service_2017-07-01-14-01-02.log
07/08/2017  02:37 PM             7,244 service_2017-07-08-14-01-02.log
07/15/2017  02:37 PM             7,244 service_2017-07-15-14-01-02.log
07/22/2017  02:37 PM             7,244 service_2017-07-22-14-01-00.log

======

Now for some ODD reason when I did boot my PC this morning it didn't seem to fully boot? Screen was colored a medium blue, and the hard disk light flickered. Pressed the power button and the PC shutdown. Then came up normally?

In the SNAPAPI folder I do see 2 entries for when I 'double booted' it seems.

==========

07/30/2017  11:29 AM           430,482 SnapAPI_2017-07-30_15-26-28_p4260.log
07/30/2017  11:29 AM           251,030 SnapAPI_2017-07-30_15-26-36_p3560.log

==========

The first one DOES have many 'errors' in the logfile.

=============

SNAPAPI: 15:26:31.650: Volume 02770638: Information:
  Id: 5
  Device: \Device\HarddiskVolume10
  Name:  <no letter>
  Size: 204800:512
  Flags: 0x5 [ ForceDismount BIOS ]
  Partition style: 1 (GPT)
  GPT id: {6DD2E87C-B0D9-4423-B909-8E302FF99C04}
  Type: {C12A7328-F81F-11D2-BA4B-00A0C93EC93B}
  Attributes: 0x8000000000000000 [ NoLetter ]
  Name: 'EFI system partition'
SNAPAPI: 15:26:33.886: Volume 02770638: Lock 1 status Error: 30 (status 5h) Text: KernelVolume[5] 1

===============

Quite a few similar to the above.

That log was over 400KB, and I have others of similar size both before and after the ones above and they too have 'error' in them.

Is this a problem?

Any reason the backup didn't start? Where should I look for clues? As far as I can recall, when the PC had been off in the past when a backup was scheduled it ran on the next boot?

0 Users found this helpful

I had a similar problem some time back. In my case it was due to a disk error on the EFI partition. So if it happens again I would eliminate that as the cause. Unfortunately it is difficult process to correct disk errors on EFI partition from within windows. 

There is an easy solution. Use the MVP Tool - CUSTOM ATI WINPE BUILDER to create recovery media (USB stick or CD/SVD). When you run the recovery media it assigns a drive letter to the EFI partition so you can open the command prompt and use the disk check with the appropriate options: the /r and /f options will probably solve the problem. 

 

Ian

En réponse à par truwrikodrorow…

Ian, maybe that explains the problem?

As I said in my initial post:

=========

Now for some ODD reason when I did boot my PC this morning it didn't seem to fully boot? Screen was colored a medium blue, and the hard disk light flickered. Pressed the power button and the PC shutdown. Then came up normally?

=========

I did see 2 log entries about 1 minute apart. When the PC first came up it was 'running' as I saw the drive light flickering. I couldn't tell why though? I pressed my power button and it immediately went into a shutdown. Powered up again and it came up normally. Wondering if one of two things had happened:

  1. PC was operating correctly, and ATI had started a backup, but the display driver didn't load properly.
  2. I had a momentary hardware problem and the SSD boot drive never came up, but that would explain why it booted the 2nd time but not why ATI didn't run?

I think I'll wait to see if the normal time will run?

I will keep the 'solution' in mind though. I did look back at the older files that were over 400KB and those all had the errors too fir the UEFI disk, so this 'problem' might have been going on for a long time. However sometimes those logs show no errors.

My boot drive is an SSD and it is over 4 years old, I could be experiencing momentary drive problems too I guess. I think any boot DVD/Flash Drive could be used for the process, even the Windows Recovery drive.

Thanks for the pointer.

Need some help here. Today it should have ran. DID NOT?

What should I be looking for?

I can not even find a TASK for Acronis? However Scheduler2 service IS running?

Suggestions please?

Irv, Acronis does not use the Windows Task Scheduler for its tasks, there is a separate Acronis task scheduler that runs in the background.

You can use the Acronis Scheduler Manager program (link in my signature) to see what tasks are in the schedule by using the 'get list' command after running the scheduler manager tool.

You will also need to use the MVP Log Viewer tool to look at the logs for the scheduler as these are separate to the normal backup task logs, and are not particularly easy to read either!

Thanks Steve...

Wouldn't you know it, I just woke up the PC and started to read this and it started to run...

Output from the Schedule Manager:

==========

inst=den start=yes loc task=0-0 > get list
 Id ExecApp ExecCmd
--- ---------------
CurUser 1 (Irv8700\irvsp):
  1-26 ~*TrueImageHomeNotify* /dummy /script:"14267A53-DF4E-4FCC-A637-D474537F21
2E" /uuid:"14267A53-DF4E-4FCC-A637-D474537F212E" /run_mode:?RunMode?
  1-28 ~*TrueImageHomeNotify* /dummy /script:"84CEBB7D-6627-45CA-B08A-38D6D6D85D
F2" /uuid:"84CEBB7D-6627-45CA-B08A-38D6D6D85DF2" /run_mode:?RunMode?
  1-32 ~*TrueImageHomeNotify* /dummy /script:"194F2F15-0AB2-499A-94AD-1272902E36
A9" /uuid:"194F2F15-0AB2-499A-94AD-1272902E36A9" /run_mode:?RunMode?
  1-36 ~*TrueImageHomeNotify* /dummy /script:"93A13DC7-4DA5-4746-A31B-22E6990347
2D" /uuid:"93A13DC7-4DA5-4746-A31B-22E69903472D" /run_mode:?RunMode?
  1-40 ~*TrueImageHomeNotify* /dummy /script:"6979DAC8-2FE3-4DEE-BF65-74F3432D97
9E" /uuid:"6979DAC8-2FE3-4DEE-BF65-74F3432D979E" /run_mode:?RunMode?
  1-41  C:\Program Files (x86)\Acronis\TrueImageHome\TrueImageMonitor.exe /shutu
p
inst=den start=yes loc task=0-0 >

=============

I'm only running and have one backup scenario, 5 drives, C:., K:, a Recovery Drive, a FAT32 (14-2), and an NTFS (14-5) drive?

Suspect 14-x is disk 1. partition 2 and 5:

================

DISKPART> select disk 1

Disk 1 is now the selected disk.

DISKPART> list part

  Partition ###  Type              Size     Offset
  -------------  ----------------  -------  -------
  Partition 1    Recovery           450 MB  1024 KB
  Partition 2    System             100 MB   451 MB
  Partition 3    Reserved            16 MB   551 MB
  Partition 4    Primary            110 GB   567 MB
  Partition 5    Recovery           460 MB   111 GB

=================

Partition 4 above is C:. K: is on disk 0, partition 7.

==============

DISKPART> select disk 0

Disk 0 is now the selected disk.

DISKPART> list part

  Partition ###  Type              Size     Offset
  -------------  ----------------  -------  -------
  Partition 1    System             500 MB  1024 KB
  Partition 2    Primary             40 MB   501 MB
  Partition 3    Reserved           128 MB   541 MB
  Partition 4    Primary            490 MB   669 MB
  Partition 5    Primary            109 GB  1159 MB
  Partition 6    Recovery           449 MB   110 GB
  Partition 7    Primary            811 GB   111 GB
  Partition 8    Primary              8 GB   922 GB

=========

This is what I've got set to backup in ATI:

https://forum.acronis.com/sites/default/files/users/user229471/Capture…

It did finish... LOG shows one error?

=============

log uuid=11DBBFF8-3686-4CF1-9405-51792CFB9E62; product=True Image; version=19.0; build=6595; task=14267A53-DF4E-4FCC-A637-D474537F212E; task_name=All Drives C and K
Start= 8/5/2017 2:01:02 PM
id=1; date/time=8/5/2017 2:01:02 PM; message=Backup reserve copy attributes: format tib; need_reserve_backup_copy false
id=2; date/time=8/5/2017 2:01:02 PM; message=Operation All Drives C and K started by schedule.
id=3; date/time=8/5/2017 2:01:07 PM; message=Backup reserve copy attributes: format tib; need_reserve_backup_copy false
id=4; date/time=8/5/2017 2:01:07 PM; message=Operation: Backup
id=5; date/time=8/5/2017 2:01:07 PM; message=Priority changed to Low.
id=6; date/time=8/5/2017 2:01:07 PM; message=Create Backup Archive From: Disk 2, Disk_K (K:) To file: P:\Acronis_Backup\All Drives\All Drives C and K.tib Compression: Normal
id=7; date/time=8/5/2017 2:01:07 PM; message=Pending operation 172 started: 'Creating partition image'.
id=8; date/time=8/5/2017 2:03:04 PM; message=Writing full version to file: All Drives C and K_full_b37_s1_v1.tib
id=9; date/time=8/5/2017 2:33:03 PM; message=Pending operation 172 started: 'Creating partition image'.
id=10; date/time=8/5/2017 2:33:06 PM; message=Pending operation 172 started: 'Creating partition image'.
id=11; date/time=8/5/2017 2:33:06 PM; message=Pending operation 172 started: 'Creating partition image'.
id=12; date/time=8/5/2017 2:40:09 PM; message=Pending operation 172 started: 'Creating partition image'.
id=13; date/time=8/5/2017 2:40:12 PM; message=Pending operation 172 started: 'Creating partition image'.
id=14; date/time=8/5/2017 2:40:12 PM; message=Pending operation 172 started: 'Creating partition image'.
id=15; date/time=8/5/2017 2:40:12 PM; message=The following backups have been successfully created: P:\Acronis_Backup\All Drives\All Drives C and K_full_b37_s1_v1.tib
id=16; date/time=8/5/2017 2:40:12 PM; message=Consolidate Backup Archive Location: P:\Acronis_Backup\All Drives\All Drives C and K_full_b37_s1_v1.tib Destination: P:\Acronis_Backup\All Drives\All Drives C and K_full_b37_s1_v1_238DE1D2-64B9-42EE-9C27-297C5233E829.tib
Error_Code=AQ4A5QHMRaELzE1bbTxib2xkPkNvbnNvbGlkYXRlIEJhY2t1cCBBcmNoaXZlPC9ib2xkPjxlbmRsLz48dGFicG9pbnQgdmFsdWU9MzA+PGluZGVudCB2YWx1ZT00PkxvY2F0aW9uOgk8aW5kZW50IHZhbHVlPTEwPjx0ZXh0Y29sb3IgdmFsdWU9Im5hdnlibHVlIj4iUDpcQWNyb25pc19CYWNrdXBcQWxsIERyaXZlc1xBbGwgRHJpdmVzIEMgYW5kIEtfZnVsbF9iMzdfczFfdjEudGliIjwvdGV4dGNvbG9yPjwvaW5kZW50PjxpbmRlbnQgdmFsdWU9ND48ZW5kbC8+RGVzdGluYXRpb246CTxpbmRlbnQgdmFsdWU9MTA+PHRleHRjb2xvciB2YWx1ZT0ibmF2eWJsdWUiPiJQOlxBY3JvbmlzX0JhY2t1cFxBbGwgRHJpdmVzXEFsbCBEcml2ZXMgQyBhbmQgS19mdWxsX2IzN19zMV92MV8yMzhERTFEMi02NEI5LTQyRUUtOUMyNy0yOTdDNTIzM0U4MjkudGliIjwvdGV4dGNvbG9yPjwvaW5kZW50PjxpbmRlbnQgdmFsdWU9ND48ZW5kbC8+PC9pbmRlbnQ+PGVuZGwvPgAkbW9kdWxlAEF0aV9kZW1vbl92c182NTk1ACRmaWxlAEFrOlw2NTk1XHByb2R1Y3RzXGltYWdlclxhcmNoaXZlXGltcGxcb3BlcmF0aW9uc1xhcmNoaXZlX29wZXJhdGlvbl9jbG9uZS5jcHAAJGZ1bmMAQVRydWVJbWFnZTo6QXJjaGl2ZTo6Q2xvbmVBcmNoaXZlT3BlcmF0aW9uSW1wbDo6UHJlcGFyZQAkbGluZQBOzgcAAAAAAAAABwQLAMxFoQvMTVttPGJvbGQ+Q29uc29saWRhdGUgQmFja3VwIEFyY2hpdmU8L2JvbGQ+PGVuZGwvPjx0YWJwb2ludCB2YWx1ZT0zMD48aW5kZW50IHZhbHVlPTQ+TG9jYXRpb246CTxpbmRlbnQgdmFsdWU9MTA+PHRleHRjb2xvciB2YWx1ZT0ibmF2eWJsdWUiPiJQOlxBY3JvbmlzX0JhY2t1cFxBbGwgRHJpdmVzXEFsbCBEcml2ZXMgQyBhbmQgS19mdWxsX2IzN19zMV92MS50aWIiPC90ZXh0Y29sb3I+PC9pbmRlbnQ+PGluZGVudCB2YWx1ZT00PjxlbmRsLz5EZXN0aW5hdGlvbjoJPGluZGVudCB2YWx1ZT0xMD48dGV4dGNvbG9yIHZhbHVlPSJuYXZ5Ymx1ZSI+IlA6XEFjcm9uaXNfQmFja3VwXEFsbCBEcml2ZXNcQWxsIERyaXZlcyBDIGFuZCBLX2Z1bGxfYjM3X3MxX3YxXzIzOERFMUQyLTY0QjktNDJFRS05QzI3LTI5N0M1MjMzRTgyOS50aWIiPC90ZXh0Y29sb3I+PC9pbmRlbnQ+PGluZGVudCB2YWx1ZT00PjxlbmRsLz48L2luZGVudD48ZW5kbC8+ACRtb2R1bGUAQXRpX2RlbW9uX3ZzXzY1OTUAJGZpbGUAQWs6XDY1OTVccHJvZHVjdHNcaW1hZ2VyXGFyY2hpdmVcaW1wbFxvcGVyYXRpb25zXGFyY2hpdmVfb3BlcmF0aW9uX2Nsb25lLmNwcAAkZnVuYwBBVHJ1ZUltYWdlOjpBcmNoaXZlOjpDbG9uZUFyY2hpdmVPcGVyYXRpb25JbXBsOjpQcmVwYXJlACRsaW5lAE7OBwAAAAAAAAA=
id=17; date/time=8/5/2017 2:40:12 PM; message=Consolidate Backup Archive Location: P:\Acronis_Backup\All Drives\All Drives C and K_full_b37_s1_v1.tib Destination: P:\Acronis_Backup\All Drives\All Drives C and K_full_b37_s1_v1_238DE1D2-64B9-42EE-9C27-297C5233E829.tib
id=18; date/time=8/5/2017 2:40:12 PM; message=Deleting backup file with path P:\Acronis_Backup\All Drives\All Drives C and K_full_b29_s1_v1.tib.
id=19; date/time=8/5/2017 2:40:13 PM; message=Operation has succeeded.
id=20; date/time=8/5/2017 2:40:17 PM; message=The e-mail notification has been successfully sent.
id=21; date/time=8/5/2017 2:40:17 PM; message=Batch operation has completed successfully.
Start= 8/5/2017 2:01:02 PM
Finish= 8/5/2017 2:40:17 PM
Total Time= 00:39:15

==================

Well as you can see it started at 2PM, my bad, I thought it was to be run at 10AM, so that is OK.

The error though I can't figure out... here is the folder after the run:

=============

P:\Acronis_Backup\All Drives>dir
 Volume in drive P is P_Drive
 Volume Serial Number is BEE5-01F4

 Directory of P:\Acronis_Backup\All Drives

08/05/2017  02:40 PM    <DIR>          .
08/05/2017  02:40 PM    <DIR>          ..
06/10/2017  02:36 PM   236,748,911,104 All Drives C and K_full_b30_s1_v1.tib
06/17/2017  02:36 PM   233,140,836,864 All Drives C and K_full_b31_s1_v1.tib
06/24/2017  03:47 PM   230,386,914,304 All Drives C and K_full_b32_s1_v1.tib
07/01/2017  02:35 PM   252,346,792,960 All Drives C and K_full_b33_s1_v1.tib
07/08/2017  02:37 PM   249,240,411,648 All Drives C and K_full_b34_s1_v1.tib
07/15/2017  02:37 PM   247,915,471,872 All Drives C and K_full_b35_s1_v1.tib
07/22/2017  02:37 PM   242,181,755,392 All Drives C and K_full_b36_s1_v1.tib
08/05/2017  02:40 PM   253,441,012,736 All Drives C and K_full_b37_s1_v1.tib
               8 File(s) 1,945,402,106,880 bytes
               2 Dir(s)  2,054,873,083,904 bytes free

============

Supposed to have 8 iterations which it does? All backups seem OK?

 

Irv, the error code isn't saying anything that isn't already shown in plain English in the log itself - for some reason Acronis just encode that text as Base64 which renders it very cryptic for no good reason that I can think of!  The code can be decrypted in NotePad++ to show the actual text but it is not a pretty sight!

Next, the output from the Scheduler Manager 'get list' command contains references to files stored in your C:\ProgramData\Acronis\TrueImageHome\Scripts folder where you will see a number of .TIB.TIS files which use the same names as the UUID / Script values shown in the output.  You can open these files in any text editor and see what each entry relates to, i.e. any validation actions etc.

Sounds from your comments that things are going OK - just a confusion over when the task was set to run?

En réponse à par truwrikodrorow…

Steve, never though of decoding it... did :

 

--------

傌EK͍[m<bold>Consolidate Backup Archive</bold><endl/><tabpoint value=30><indent value=4>Location:    <indent value=10><textcolor value="navyblue">"P:\Acronis_Backup\All Drives\All Drives C and K_full_b37_s1_v1.tib"</textcolor></indent><indent value=4><endl/>Destination:    <indent value=10><textcolor value="navyblue">"P:\Acronis_Backup\All Drives\All Drives C and K_full_b37_s1_v1_238DE1D2-64B9-42EE-9C27-297C5233E829.tib"</textcolor></indent><indent value=4><endl/></indent><endl/> $module Ati_demon_vs_6595 $file Ak:\6595\products\imager\archive\impl\operations\archive_operation_clone.cpp $func ATrueImage::Archive::CloneArchiveOperationImpl::Prepare $line N·       ͅK͍[m<bold>Consolidate Backup Archive</bold><endl/><tabpoint value=30><indent value=4>Location:    <indent value=10><textcolor value="navyblue">"P:\Acronis_Backup\All Drives\All Drives C and K_full_b37_s1_v1.tib"</textcolor></indent><indent value=4><endl/>Destination:    <indent value=10><textcolor value="navyblue">"P:\Acronis_Backup\All Drives\All Drives C and K_full_b37_s1_v1_238DE1D2-64B9-42EE-9C27-297C5233E829.tib"</textcolor></indent><indent value=4><endl/></indent><endl/> $module Ati_demon_vs_6595 $file Ak:\6595\products\imager\archive\impl\operations\archive_operation_clone.cpp $func ATrueImage::Archive::CloneArchiveOperationImpl::Prepare $line N·      

-----

Sort of confused and not understanding the above at all? Reason being the log sent to my e-mail for step 16 doesn't show an error???

=========

16    True Image    8/5/2017 2:40:12 PM    Consolidate Backup Archive Location:	"P:\Acronis_Backup\All Drives\All Drives C and K_full_b37_s1_v1.tib" Destination:	"P:\Acronis_Backup\All Drives\All Drives C and K_full_b37_s1_v1_238DE1D2-64B9-42EE-9C27-297C5233E829.tib" 

==========

Yes, everything appears to be OK too.

I'm still getting those SNAPAPI errors though. I thought with the suggestion from IanL-S I had that solved. I ran the W10 Defragment and Optimization and those 2 drives said they had not been Optimized in a long time. Ran it on those 2 drives and had to reboot. It did 'perform' something rather fast on reboot, so I thought it was fixed. It isn't, I just ran Defrag and Opt. again and it still shows as needing it. I guess I'll need to boot to an alternate source and try CHKDSK on those drives, that is if this is really a problem that would hinder recover. I'd think not though, if I couldn't recover I'd probably just install Windows on the SSD wiping out everything and then just recover the C: partition anyway?