Salta al contenuto principale

Backup failed to run

Thread needs solution

A scheduled backup failed to run on 11 Mar 2017 at 2215.  I have read through the various troubleshooting stuff and not found a specific cause.

The options to wake the sleeping computer are set.

I know it would probably work if I created a new task.  But sometimes I need to edit the existing task to turn off the backup scheduling, and I would hate to have to create a new task each time.  I was hoping someone could review the attached log and provide me a reason for the failure to run, and possibly a fix.

So far as the log is concerned, I generated it using the MVP Log Viewer, but when I tried to save it using the "Save as log file" option, the program crashed with this error message:

  "Error in SaveAsLogFileToolStripMenuItem_Click"

The scheduler2 log file is attached.  The scheduled backup time/frequency is daily / 10:15 PM

Thanks for any tips

Allegato Dimensione
acronis_sch2_log.zip 3.49 KB
0 Users found this helpful

Ron, the schedul2 log does not show me any error indication for why your scheduled backup failed to run but it does show a long list of different backup tasks that are present in your system as shown below:

11.03.2017 22:15:01.051 000000000000076C OnServiceMainWake: begin
11.03.2017 22:15:01.051 000000000000076C Processing task 1-2
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 1-5
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 1-6
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 1-7
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 1-17
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 1-23
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 1-41
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 1-42
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 1-44
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 1-49
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 1-50
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 1-53
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 1-54
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 1-55
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 1-59
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 1-60
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 1-62
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 1-63
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 2-3
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 2-5
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 2-7
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Processing task 2-8
11.03.2017 22:15:01.051 000000000000076C RepStartFlag : 0
11.03.2017 22:15:01.051 000000000000076C Conditions: isNextRepeated: 0, onTaskFinished: 0, checkedPast: 0, checkedFuture: 1, repeatedOrNotChecked: 1, doRun: 0
11.03.2017 22:15:01.051 000000000000076C SetWaitableTimer(-863390000000): error=0
11.03.2017 22:15:01.052 000000000000076C OnServiceMainWake: next time = 12.03.2017 22:15:00

Error = 0 at the end says that no error occurred, but each task beginning 1- or 2- represents a different backup or validation activity for which a schedule entry exists. You would then need to use the Acronis Scheduler Manager tool (link below) to see what each of these tasks actually are?

Run schedmgr.exe as Administrator, then issue command: get list in the windows that is opened and you will see a list with these 1- & 2- task identifiers, plus a long complex identifier which will match files found in C:\ProgramData\Acronis\TrueImageHome\Scripts as .TIS files - these files are XML text formatted and can be opened in Notepad, where the task name is found near the top inside <display>My backup name</display> brackets.

For the error with the MVP Log Viewer, please drop a PM to FtrPilot so that he can take a look at what is happening there with his code.

Thanks Steve,

So the scheduler ran at the specific time, but TrueImage did not start the requisite task

In the Scripts folder, there are only two scripts listed (also found in the Get List command). 

I searched my computer, for one of the other tasks listed, but nothing found.

I searched the Registry, and found nine of the seventeen similar tasks (from Get List) listed under:
       HKEY_CURRENT_USER\SOFTWARE\Acronis\Acronis True Image 2016\RestoreTask

Some that were not listed in that key were in
    HKEY_LOCAL_MACHINE\SOFTWARE\Acronis\TrueImageHome\TaskLastExitCodes

but there were others listed in that last key that were not returned by the Get List command.

Probably doesn't mean much.

So the Scheduler ran at the appropriate time; it processed the script (1-55 in the list), but the script did not start.  The script did start by the scheduler two days ago, and I did not edit it between then and now.

Thanks for looking at that file, though.  And if you have any other thoughts, I would appreciate it.

Ron

 

 

 

 

Ron, do you have any Service_ logs for your backup task from around the time when it should have been run on schedule?

Ron, not sure what else to say as to why the task didn't run on schedule.

You can either wait to see if the scheduled task runs next time or else can use the Backup now option to run it immediately.

The further option is to use the Acronis Scheduler Manager tool to clear out all your scheduled task and reset the schedule(s) for the task(s) that should have these.  Use the command task zap to do this.

Hi

I'm having a very similar issue.  A weekly task sometimes runs and sometimes doesnt and produces no useful information as to why it hasnt run.  I've tried that task zap option and reset it to run again next week, hopefully that fixes it.  

Paul

Thanks Steve,

     I ran the task manually.  But I plan to use the scheduler to remove all the unwanted tasks, and start afresh.  We'll see what happens.

Ron, Paul, if the computer is asleep or in hibernation mode, when the backup is supposed to run, please check if the AC power is on or off. Some BIOS/UEFI settings do not allow wakeup triggers to wake up the computer if the AC power is off.

Regards,

Slava

Slava,

AC Power where?  If there is no AC Power going to the computer, nothing will turn it on.

I will recheck the BIOS settings, but I don't think that is the case since the Windows scheduler can wake up the computer.

 

If you have a mobile device (laptop) with a battery, it works without AC power.  Mobile devices have different behavior depending upon whether it's running on AC power or battery power.  If you have a desktop, then it would only be AC power, but if using a laptop, the sleep behavior can vary if it os battery instead of directly plugged in. 

As an FYI - maybe check your Acronis services (control panel >>> administratrtive tools >>> compouter management >>> serviceds) and see what the Acronis Scheduler service is set to.  See if it is 1) enabled.  2) if it is enabled, is it set to start "automatic" or "disabled".  If it is set to "automatic" you might try setting it to "automatic delayed" to see if giving it a few more seconds will allow the scheduler service to kick in as perhaps there are other services taking a bit longer to come on line when returning from sleep that may be requiring a little more time for the scheduler service to function correctly.

Another user had a similar issue and found that scripting a task to stop and start the scheduler service is working much better, and am wondering if just delaying the startup of it a bit longer would have a similar effect and make it more reliable for those that are experiencing this behavior.

Here is BrunoC's thread from the 2017 forum...

https://forum.acronis.com/forum/129173#comment-408152

OK, that makes sense about the AC power question.  I don't have any portable computers that I use TIH on, so that never occurred to me.

I'll try the delayed start.  And I assume you mean Scheduler2 service (I don't see any Scheduler Service).

In the past (perhaps the 2007 version), I would use the Windows scheduler to schedule a wake up a few minutes before Acronis startup time, but I think that was a different issue.

Ron, yes it is the schedul2 service that you should try setting to Automatic Delayed Start.

Ron, the other user (Bruno) in the post that Rob gave the link for found that he needed to stop & restart the Acronis Schedul2 service a couple of minutes before the scheduled task was due to run - he has got an open Support Case with Acronis for this issue.

You could put such as the following lines into a batch command file and then run this from the Windows Task Scheduler - you should run the batch commands with Administrator rights.

@echo off
sc stop "AcrSch2Svc" > NUL 2>&1
TIMEOUT /T 45 > NUL
sc start "AcrSch2Svc" > NUL 2>&1
timeout /t 10
exit

Steve, 

Yes, I saw that and I've set up the Task Scheduler to run a .bat file at the appropriate time.  We'll see what happens.

Ron