ATI 2017 Simply Stops Backing Up
I'm a long time (successful) Acronis backup user and I need help. I recently built a new computer that replaced my old one and so far I've been unable to get ATI to work successfully. I've installed/uninstalled/re-installed and I have the same symptoms. I do a nightly File Backup using the Version Chain scheme and the initial full backup doesn't complete. The first time this happened I ended ATI through the task manage and when I restarted ATI it created a full backup and completed and the nightly backups completed successfully. When it was time for the next full backup that backup failed (just like the first time) and that is when I uninstalled/reinstalled. Once again, the first execution of the full backup stopped most of the way through and that is where I am at now. The attached photo show the current state. I've always used Max compression. Any thoughts or suggestions?
- Windows 10 64 bit
- i7-7820X processor
- 32GB memory
- C: two m.2 SSD in RAID0
- D: SSD
- F: 4TB USB backup drive
Bob, how full is your F: drive? Your screen shot does not blow up well, but it looks like Acronis says 417.9 GB of 3.6 TB free. And your full backups look like they are over 400 GB.
Thanks for the prompt response Steve. Here is what is in the log and it doesn't look like much:
2017-08-20 10:25:52:757 11088 I00640000: Backup reserve copy attributes: format tib; need_reserve_backup_copy false;
2017-08-20 10:25:52:757 11088 I00640002: Operation My Files started manually.
2017-08-20 10:25:54:617 11088 I00640000: Backup reserve copy attributes: format tib; need_reserve_backup_copy false;
2017-08-20 10:25:54:632 11088 I013C0000: Operation: Backup
2017-08-20 10:25:54:632 11088 I0064000B: Priority changed to Low.
2017-08-20 10:25:58:836 11088 I000B03F0: Create Backup Archive From: C:\Users\Bob\
D:\Music\
D:\OneDrive\
D:\Pictures\
D:\Projects\
D:\Videos\
To file: My Files_full_b1_s1_v1.tib Compression: Maximum Exclude: System files
2017-08-20 10:25:59:398 7308 I00640000: Writing full version to file: My Files_full_b1_s1_v1.tib
2017-08-20 11:49:51:167 1148 W00640000: The operations have been canceled due to the system shutdown.
2017-08-20 11:50:51:174 6424 E00640000: This operation has been terminated due to time out.
Bob, you have one answer shown from the log you posted:
2017-08-20 11:49:51:167 1148 W00640000: The operations have been canceled due to the system shutdown.
2017-08-20 11:50:51:174 6424 E00640000: This operation has been terminated due to time out.
In your ATIH Schedule tab settings, open the Advanced settings section of the page and check that you have the option for "Prevent the computer from going to sleep/hibernate" enabled.
Thanks and I'll make that change. In the meantime I created the same backup only with normal compression and it ran to completion. Maybe that (also) had something to do with it.
BTW, I checked the prevent sleep setting and it was already set.
Bob, can you check the Windows event logs to see if there was anything amiss at the time the operation was cancelled? I'm wondering if there was some fault with an Acronis service that caused it to fail. It sort of looks like one part of Acronis may have failed and the part running the task timed out after a minute.
There was a VSS error at 1:33 AM when the full backup was running. In addition to that there were four Acronis Scheduler errors. There was also the following error at 10:06:
Log Name: Application
Source: Application Error
Date: 8/20/2017 10:06:06 AM
Event ID: 1000
Task Category: (100)
Level: Error
Keywords: Classic
User: N/A
Computer: BSystemX299
Description:
Faulting application name: sync_info_remover.exe, version: 20.0.1.3045, time stamp: 0x58be8266
Faulting module name: KERNELBASE.dll, version: 10.0.15063.502, time stamp: 0xc3955624
Exception code: 0xc06d007e
Fault offset: 0x000eb802
Faulting process id: 0x41c4
Faulting application start time: 0x01d319c5d8b62e77
Faulting application path: C:\Users\Bob\AppData\Local\Temp\D79E2DC0-3849-4528-BB23-E6D9CB68BBC1\sync_info_remover.exe
Faulting module path: C:\WINDOWS\System32\KERNELBASE.dll
Report Id: e5557c72-5ec6-4247-a1eb-887131f5c94c
Faulting package full name:
Faulting package-relative application ID:
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
<System>
<Provider Name="Application Error" />
<EventID Qualifiers="0">1000</EventID>
<Level>2</Level>
<Task>100</Task>
<Keywords>0x80000000000000</Keywords>
<TimeCreated SystemTime="2017-08-20T15:06:06.241381300Z" />
<EventRecordID>2255</EventRecordID>
<Channel>Application</Channel>
<Computer>BSystemX299</Computer>
<Security />
</System>
<EventData>
<Data>sync_info_remover.exe</Data>
<Data>20.0.1.3045</Data>
<Data>58be8266</Data>
<Data>KERNELBASE.dll</Data>
<Data>10.0.15063.502</Data>
<Data>c3955624</Data>
<Data>c06d007e</Data>
<Data>000eb802</Data>
<Data>41c4</Data>
<Data>01d319c5d8b62e77</Data>
<Data>C:\Users\Bob\AppData\Local\Temp\D79E2DC0-3849-4528-BB23-E6D9CB68BBC1\sync_info_remover.exe</Data>
<Data>C:\WINDOWS\System32\KERNELBASE.dll</Data>
<Data>e5557c72-5ec6-4247-a1eb-887131f5c94c</Data>
<Data>
</Data>
<Data>
</Data>
</EventData>
</Event>
I don't know what this program does, but the error was before the backup so probably not related. What about the Acronis Scheduler errors. Anything around 11:49 AM? Look for other system activity, possibly with a warning. Just trying to get an idea of what was going on besides the backup that could have affected it.
Just a bunch of unrelated Informational messages and here are the details of the Scheduler error at 11:49:51:
Log Name: Application
Source: Acronis Scheduler
Date: 8/20/2017 11:49:51 AM
Event ID: 1
Task Category: None
Level: Error
Keywords: Classic
User: SYSTEM
Computer: BSystemX299
Description:
Scheduler failed to run task >> "" with GUID '46860511-9177-4802-9DEC-AFE7902D563D' because of error 87> (Scheduler has received a request with an invalid parameter.).
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
<System>
<Provider Name="Acronis Scheduler" />
<EventID Qualifiers="0">1</EventID>
<Level>2</Level>
<Task>0</Task>
<Keywords>0x80000000000000</Keywords>
<TimeCreated SystemTime="2017-08-20T16:49:51.152213300Z" />
<EventRecordID>2349</EventRecordID>
<Channel>Application</Channel>
<Computer>BSystemX299</Computer>
<Security UserID="S-1-5-18" />
</System>
<EventData>
<Data>Scheduler failed to run task >> "" with GUID '46860511-9177-4802-9DEC-AFE7902D563D' because of error 87> (Scheduler has received a request with an invalid parameter.).</Data>
</EventData>
</Event>
Bob, the Acronis Scheduler error is at the same time as the log entry about system shutdown.
I have been seeing a problem in the ATI 2018 Beta that causes these errors. It seems that when a backup task is being run, Acronis schedules a task to be run one time when the system is shut down. I never had the problem in ATI 2017 because I assume the task was properly deleted when the backup finished (but in ATI 2018 is left in the schedule). I have no idea what the task is because the invalid parameter is basically a complete blank command line. In your case, the backup did not finish and the scheduler service is being terminated, which should only happen due to system shutdown (or when you explicitly stop the service). When the service terminates, it tries to run that task.
So... why would it want to shut down? Could it be overheating? Since you were doing a full compression backup, I could see that causing the CPU to heat up a lot. But then, why would the shutdown not complete? Perhaps something is telling Windows not to shut down yet. I have no idea... just thinking out loud.