Skip to main content

Scheduled Backups never run on W7 machine

Thread needs solution

I'm trying for a while but have a scheduled backup on one machine and never get it running, even with uninstalling and re-installing the Acronis Backup Agent, using other accounts for the services and so on. Does anyone maybe have some similar experiences and could find a solution? 

The Machine to be backed up is a Windows 7 machine with always 2 users logged in, one standard user and one administrator (logged in but switched user). When I start a backup form Acronis Backup Manager, there is no problem to run the job (once). I already tried to drop and recreate the backup-plan, reinstall the agent and use other target locations. The same backup plan runs fine on two other machines. 

The problem seems to be at the scheduler, but I couldn't find what's wrong.

Here is one of the last logs at a scheduled backup time, thanks in advance for your ideas:

 

22/11/2018 15:29:35: OnServiceMainWake: begin
22/11/2018 15:29:35: Trying task 2-13 as TIME_NORMAL
22/11/2018 15:29:35: Already waiting
22/11/2018 15:29:35: Exclusive
22/11/2018 15:29:35: OnServiceMainWake: next time = 22.11.2018 15:35:03
22/11/2018 15:35:05: OnServiceMainWake: begin
22/11/2018 15:35:05: Trying task 3-3 as TIME_NORMAL
22/11/2018 15:35:05: Already waiting
22/11/2018 15:35:05: Exclusive
22/11/2018 15:35:05: OnServiceMainWake: next time = 22.11.2018 15:43:23
22/11/2018 15:43:24: OnServiceMainWake: begin
22/11/2018 15:43:24: Trying task 2-16 as TIME_NORMAL
22/11/2018 15:43:24: Stored token 00000000 replaced with 00000000
22/11/2018 15:43:24: OnServiceMainWake: next time = 22.11.2018 16:00:00
22/11/2018 15:43:24: API Reply: OP=WAIT_CHANGES_EX Ver=4 Status=0 Task=2-16 Result=3(RUN_WAITED) Extra=8(TIME_NORMAL)
22/11/2018 15:43:24: Running task 3-3 pending because of: IDLE LOGON
22/11/2018 15:43:24: Running task 3-2 pending because of: IDLE LOGON
22/11/2018 15:43:24: Running task 2-13 pending because of: IDLE
22/11/2018 15:43:24: Running task 2-12 pending because of: IDLE
22/11/2018 15:43:24: Running task 2-16 pending because of: IDLE
22/11/2018 15:43:24: Runner wait for 4294967295 ms
22/11/2018 15:43:24: API Entry: OP=WAIT_CHANGES_EX Ver=4 Misc=0 Id=0 IntUser=-1 LogonSid= User="NT AUTHORITY\SYSTEM" (S-1-5-18)
22/11/2018 15:43:24: WaitId=219 Product=4 (ABR)
22/11/2018 15:43:24: API Reply: OP=WAIT_CHANGES_EX Ver=4 Status=0 Task=3-3 Result=6(RUN_PENDING) Extra=129(IDLE LOGON)
22/11/2018 15:43:24: API Entry: OP=WAIT_CHANGES_EX Ver=4 Misc=0 Id=0 IntUser=-1 LogonSid= User="NT AUTHORITY\SYSTEM" (S-1-5-18)
22/11/2018 15:43:24: WaitId=219 Product=4 (ABR)
22/11/2018 15:43:24: API Reply: OP=WAIT_CHANGES_EX Ver=4 Status=0 Task=3-2 Result=6(RUN_PENDING) Extra=129(IDLE LOGON)
22/11/2018 15:43:24: API Entry: OP=WAIT_CHANGES_EX Ver=4 Misc=0 Id=0 IntUser=-1 LogonSid= User="NT AUTHORITY\SYSTEM" (S-1-5-18)
22/11/2018 15:43:24: WaitId=219 Product=4 (ABR)
22/11/2018 15:43:24: API Reply: OP=WAIT_CHANGES_EX Ver=4 Status=0 Task=2-13 Result=6(RUN_PENDING) Extra=128(IDLE)
22/11/2018 15:43:24: API Entry: OP=WAIT_CHANGES_EX Ver=4 Misc=0 Id=0 IntUser=-1 LogonSid= User="NT AUTHORITY\SYSTEM" (S-1-5-18)
22/11/2018 15:43:24: WaitId=219 Product=4 (ABR)
22/11/2018 15:43:24: API Reply: OP=WAIT_CHANGES_EX Ver=4 Status=0 Task=2-12 Result=6(RUN_PENDING) Extra=128(IDLE)
22/11/2018 15:43:24: API Entry: OP=WAIT_CHANGES_EX Ver=4 Misc=0 Id=0 IntUser=-1 LogonSid= User="NT AUTHORITY\SYSTEM" (S-1-5-18)
22/11/2018 15:43:24: WaitId=219 Product=4 (ABR)
22/11/2018 15:43:24: API Reply: OP=WAIT_CHANGES_EX Ver=4 Status=0 Task=2-16 Result=6(RUN_PENDING) Extra=128(IDLE)
22/11/2018 15:43:24: API Entry: OP=WAIT_CHANGES_EX Ver=4 Misc=0 Id=0 IntUser=-1 LogonSid= User="NT AUTHORITY\SYSTEM" (S-1-5-18)
22/11/2018 15:43:24: WaitId=219 Product=4 (ABR)
22/11/2018 16:00:01: OnServiceMainWake: begin
22/11/2018 16:00:01: Trying task 2-2 as TIME_NORMAL
22/11/2018 16:00:01: Stored token 00000000 replaced with 00000000
22/11/2018 16:00:01: OnServiceMainWake: next time = 22.11.2018 19:00:00
22/11/2018 16:00:01: Running task 3-3 pending because of: IDLE LOGON
22/11/2018 16:00:01: Running task 3-2 pending because of: IDLE LOGON
22/11/2018 16:00:01: Running task 2-13 pending because of: IDLE
22/11/2018 16:00:01: Running task 2-12 pending because of: IDLE
22/11/2018 16:00:01: Running task 2-16 pending because of: IDLE
22/11/2018 16:00:01: Running task 2-2
22/11/2018 16:00:01: DuplicateTokenEx: < token=000002EC > error=0 token=0000018C
22/11/2018 16:00:01: NewSecurityDescriptor: > error=0
22/11/2018 16:00:01: SetTokenInformation: > error=0
22/11/2018 16:00:01: Scheduler user for account found
22/11/2018 16:00:01: Session not found
22/11/2018 16:00:01: GetTokenUserName: > error=0 domain="NT AUTHORITY" user="SYSTEM"
22/11/2018 16:00:01: DsGetDcName: < domain="NT AUTHORITY" > error=1212
22/11/2018 16:00:01: GetUserProfileDirectory > error=0.
22/11/2018 16:00:01: LoadUserProfile: < user="SYSTEM" path="(null)" > error=0
22/11/2018 16:00:01: CreateEnvironmentBlock: > error=0
22/11/2018 16:00:01: CreateProcessAsUser: < token=0000018C > error=0
22/11/2018 16:00:01: Stored token 00000000 replaced with 00000000
22/11/2018 16:00:01: Runner wait for 4294967295 ms
22/11/2018 16:00:01: API Reply: OP=WAIT_CHANGES_EX Ver=4 Status=0 Task=3-3 Result=6(RUN_PENDING) Extra=129(IDLE LOGON)
22/11/2018 16:00:01: Task 2-2 completed with exit code=0
22/11/2018 16:00:01: Stored token 00000000 replaced with 00000000
22/11/2018 16:00:01: Running task 3-3 pending because of: IDLE LOGON
22/11/2018 16:00:01: Running task 3-2 pending because of: IDLE LOGON
22/11/2018 16:00:01: Running task 2-13 pending because of: IDLE
22/11/2018 16:00:01: Running task 2-12 pending because of: IDLE
22/11/2018 16:00:01: Running task 2-16 pending because of: IDLE
22/11/2018 16:00:01: Runner wait for 4294967295 ms
22/11/2018 16:00:01: API Entry: OP=WAIT_CHANGES_EX Ver=4 Misc=0 Id=0 IntUser=-1 LogonSid= User="NT AUTHORITY\SYSTEM" (S-1-5-18)
22/11/2018 16:00:01: WaitId=219 Product=4 (ABR)
22/11/2018 16:00:01: API Reply: OP=WAIT_CHANGES_EX Ver=4 Status=0 Task=3-2 Result=6(RUN_PENDING) Extra=129(IDLE LOGON)
22/11/2018 16:00:01: API Entry: OP=WAIT_CHANGES_EX Ver=4 Misc=0 Id=0 IntUser=-1 LogonSid= User="NT AUTHORITY\SYSTEM" (S-1-5-18)
22/11/2018 16:00:01: WaitId=219 Product=4 (ABR)
22/11/2018 16:00:01: API Reply: OP=WAIT_CHANGES_EX Ver=4 Status=0 Task=2-13 Result=6(RUN_PENDING) Extra=128(IDLE)
22/11/2018 16:00:01: API Entry: OP=WAIT_CHANGES_EX Ver=4 Misc=0 Id=0 IntUser=-1 LogonSid= User="NT AUTHORITY\SYSTEM" (S-1-5-18)
22/11/2018 16:00:01: WaitId=219 Product=4 (ABR)
22/11/2018 16:00:01: API Reply: OP=WAIT_CHANGES_EX Ver=4 Status=0 Task=2-12 Result=6(RUN_PENDING) Extra=128(IDLE)
22/11/2018 16:00:01: API Entry: OP=WAIT_CHANGES_EX Ver=4 Misc=0 Id=0 IntUser=-1 LogonSid= User="NT AUTHORITY\SYSTEM" (S-1-5-18)
22/11/2018 16:00:01: WaitId=219 Product=4 (ABR)
22/11/2018 16:00:01: API Reply: OP=WAIT_CHANGES_EX Ver=4 Status=0 Task=2-16 Result=6(RUN_PENDING) Extra=128(IDLE)
22/11/2018 16:00:01: API Entry: OP=WAIT_CHANGES_EX Ver=4 Misc=0 Id=0 IntUser=-1 LogonSid= User="NT AUTHORITY\SYSTEM" (S-1-5-18)
22/11/2018 16:00:01: WaitId=219 Product=4 (ABR)
22/11/2018 16:00:01: API Reply: OP=WAIT_CHANGES_EX Ver=4 Status=0 Task=3-3 Result=6(RUN_PENDING) Extra=129(IDLE LOGON)
22/11/2018 16:00:01: API Entry: OP=WAIT_CHANGES_EX Ver=4 Misc=0 Id=0 IntUser=-1 LogonSid= User="NT AUTHORITY\SYSTEM" (S-1-5-18)
22/11/2018 16:00:01: WaitId=219 Product=4 (ABR)
22/11/2018 16:00:01: API Reply: OP=WAIT_CHANGES_EX Ver=4 Status=0 Task=3-2 Result=6(RUN_PENDING) Extra=129(IDLE LOGON)
22/11/2018 16:00:01: API Entry: OP=WAIT_CHANGES_EX Ver=4 Misc=0 Id=0 IntUser=-1 LogonSid= User="NT AUTHORITY\SYSTEM" (S-1-5-18)
22/11/2018 16:00:01: WaitId=219 Product=4 (ABR)
22/11/2018 16:00:01: API Reply: OP=WAIT_CHANGES_EX Ver=4 Status=0 Task=2-13 Result=6(RUN_PENDING) Extra=128(IDLE)
22/11/2018 16:00:01: API Entry: OP=WAIT_CHANGES_EX Ver=4 Misc=0 Id=0 IntUser=-1 LogonSid= User="NT AUTHORITY\SYSTEM" (S-1-5-18)
22/11/2018 16:00:01: WaitId=219 Product=4 (ABR)
22/11/2018 16:00:01: API Reply: OP=WAIT_CHANGES_EX Ver=4 Status=0 Task=2-12 Result=6(RUN_PENDING) Extra=128(IDLE)
22/11/2018 16:00:01: API Entry: OP=WAIT_CHANGES_EX Ver=4 Misc=0 Id=0 IntUser=-1 LogonSid= User="NT AUTHORITY\SYSTEM" (S-1-5-18)
22/11/2018 16:00:01: WaitId=219 Product=4 (ABR)
22/11/2018 16:00:01: API Reply: OP=WAIT_CHANGES_EX Ver=4 Status=0 Task=2-16 Result=6(RUN_PENDING) Extra=128(IDLE)
22/11/2018 16:00:01: API Entry: OP=WAIT_CHANGES_EX Ver=4 Misc=0 Id=0 IntUser=-1 LogonSid= User="NT AUTHORITY\SYSTEM" (S-1-5-18)
22/11/2018 16:00:01: WaitId=219 Product=4 (ABR)

0 Users found this helpful
frestogaslorastaswastavewroviwroclolacorashibushurutraciwrubrishabenichikucrijorejenufrilomuwrigaslowrikejawrachosleratiswurelaseriprouobrunoviswosuthitribrepakotritopislivadrauibretisetewrapenuwrapi
Posts: 250
Comments: 7092

Hello Christoph,

thank your posting! In order to execute scheduled backups when a user is logged out or another user is logged in to the system, Acronis Managed Machine service should be run under an account with the administrative privileges (domain admin if machines are in the domain), so I'd start with checking the rights as per https://kb.acronis.com/content/56202 Please let me know if this helps.