Skip to main content

Backup timings look weird since windows reinstall

Thread needs solution

I have 2 daily backups set to run at 0200 and 0600 each night.  Prior to my recent Windows 2004 re-install, they would both run OK in the sense that the first one would finish long before the second started.

Now, it seems that they both finish around the same time - approximately 0815 - every day.

Can anyone throw some light on this?

0 Users found this helpful

The first backup just creates 1 tibx file, whereas the second does the usual series of daily tib files.

 

Attachment Size
547064-188741.jpg 54.05 KB
547064-188744.jpg 10.5 KB

Sorry but cannot give any suggestions based only on the screen images.

We would need to see the backup_worker and ti_demon logs for the two tasks or an acronis system report zip file (which captures all the log files along with task configuration etc).

If uploading the logs, then please zip the containing folder to preserve the log file original names and make them easier to identify by their date / time stamps.

Any system report zip file would need to be shared via a cloud sharing link as will be larger than the max file size of 3MB for file uploads.

See KB 58820: Acronis True Image: Collecting System Report if going that route.

Otherwise, open C:\ProgramData\Acronis\TrueImageHome\Logs then zip the backup_worker and ti_demon folders to capture the logs only.

Not sure what is happening, but I posted a link to the system report twice now, but the post doesn't show!

Drop me a private message with the link which should work ok for you.

Got the zip file and will look shortly.

Iain, from a quick look at the logs, your PC is simply not waking up for the 2:00AM task to run and this is then not running until after 8:00AM.

Sections of the Schedul2 log files:

03/08/2020 12:00:00:632 AM  Network cost changed
03/08/2020 12:00:00:635 AM  Network data plan changed
03/08/2020 08:49:02:500 AM  OnServiceMainWake: suspend
03/08/2020 08:49:02:738 AM  OnServiceTimeChange: suspend
03/08/2020 08:49:02:738 AM  Time zone: UTC+1.00 (GMT Summer Time)
03/08/2020 08:49:03:925 AM  PowerRegisterSuspendResumeNotification callback: Type = 0x7 (PBT_APMRESUMESUSPEND)
03/08/2020 08:49:03:925 AM  OnServiceMainWake: begin
03/08/2020 08:49:03:925 AM  Trying task 1-1 as TIME_NORMAL
03/08/2020 08:49:03:925 AM  Stored token 00000000 replaced with 00000000
03/08/2020 08:49:03:925 AM  Windows fast startup state to disable: ENABLED PRESENCE
03/08/2020 08:49:03:925 AM  Windows fast startup state disabled: BACKUP PRESENCE
03/08/2020 08:49:03:925 AM  PowerRegisterSuspendResumeNotification callback: Type = 0x12 (PBT_APMRESUMEAUTOMATIC)
03/08/2020 08:49:03:926 AM  OnServiceEvent(SE_POWER)
03/08/2020 08:49:03:932 AM  Trying task 1-2 as TIME_NORMAL
03/08/2020 08:49:03:932 AM  Stored token 00000000 replaced with 00000000
03/08/2020 08:49:03:939 AM  Trying task 1-3 as TIME_NORMAL
03/08/2020 08:49:03:939 AM  Stored token 00000000 replaced with 00000000
03/08/2020 08:49:03:944 AM  next wake up time = 4.08.2020 01:59:00
03/08/2020 08:49:03:944 AM  SetWaitableTimer(132409763400000000): > error=0
03/08/2020 08:49:03:945 AM  OnServiceMainWake: next time = 3.08.2020 10:44:00

04/08/2020 12:19:20:265 AM  WaitId=221 Product=2 (ATI)
04/08/2020 12:19:20:297 AM  Network cost changed
04/08/2020 12:19:20:301 AM  Network data plan changed
04/08/2020 08:13:41:500 AM  OnServiceMainWake: suspend

04/08/2020 08:13:41:888 AM  OnServiceTimeChange: suspend
04/08/2020 08:13:41:888 AM  Time zone: UTC+1.00 (GMT Summer Time)
04/08/2020 08:13:42:833 AM  PowerRegisterSuspendResumeNotification callback: Type = 0x7 (PBT_APMRESUMESUSPEND)
04/08/2020 08:13:42:834 AM  PowerRegisterSuspendResumeNotification callback: Type = 0x12 (PBT_APMRESUMEAUTOMATIC)
04/08/2020 08:13:42:834 AM  OnServiceMainWake: begin
04/08/2020 08:13:42:834 AM  Trying task 1-1 as TIME_NORMAL
04/08/2020 08:13:42:834 AM  Stored token 00000000 replaced with 00000000
04/08/2020 08:13:42:834 AM  Windows fast startup state to disable: ENABLED PRESENCE
04/08/2020 08:13:42:835 AM  Windows fast startup state disabled: BACKUP PRESENCE
04/08/2020 08:13:42:840 AM  Trying task 1-2 as TIME_NORMAL
04/08/2020 08:13:42:840 AM  Stored token 00000000 replaced with 00000000
04/08/2020 08:13:42:847 AM  Trying task 1-3 as TIME_NORMAL
04/08/2020 08:13:42:847 AM  Stored token 00000000 replaced with 00000000
04/08/2020 08:13:42:855 AM  OnServiceEvent(SE_POWER)
04/08/2020 08:13:42:855 AM  next wake up time = 5.08.2020 01:59:00
04/08/2020 08:13:42:855 AM  SetWaitableTimer(132410627400000000): > error=0
04/08/2020 08:13:42:855 AM  OnServiceMainWake: next time = 4.08.2020 10:44:00

Thanks Steve. Any idea why it won’t wake at 2am, but will at 8am?

also, what about the issue I mentioned in my 2nd post? The ‘Full C D Backup’ is only producing a single tibx file rather than multiple tib files like the other backup. 

Thanks again, Steve.  Those links are useful.  I've deleted the old backup and gone back to files and folders, so we'll see what happens tonight.

Yes, both backups were set to wake sleeping computer.

Steve, everything worked perfectly last night.  I think the problem was that on reinstalling Windows, somehow the 'Allow wake timers' in Advanced Power Settings had been set to disabled.  I changed that and all is good.

I think the 8am thing was a red herring, as probably, I was on my PC by then, so it wasn't woken by ATI at all!

Anyway, thanks again for diagnosing the issue to the PC not waking on demand.

Iain, glad all is back to working correctly!  Windows reinstall or upgrade can change settings at times so always good to do a quick check, even if for own sanity sake!