Skip to main content

SQL Backups Not Running

Thread solved

I am having an issue on multiple VM SQL servers when running SQL backups using the local installed agent for SQL. This randomly started acting up on a single server and has since started on multiple. All were working perfectly in the past but now all seem to be doing the same exact thing.

Whenever I run the jobs absolutely nothing happens on the server and it does not even register in the activities list for the machine. I have reinstalled the agent and recreated jobs multiple times with the same results. Nothing happens. If I run a full VM backup using an appliance VM it works but the SQL application backup does not register and I cannot recover individual SQL data like when using the SQL backup.

When checking the logs I get the below errors but cannot decipher exactly what the issue is. All servers I am having issues with show very similar entries in the logs so I'm thinking if I can get one working then I can resolve the others as well. Any help is greatly appreciated.

 

2020-07-28T14:21:37:961-04:00 3876 I00000000: Launch scheduled command 'D77E80BD-410A-468E-B593-FC8467A0D91B'
2020-07-28T14:21:37:961-04:00 368 I0135003A: User is running command. Command=Tol::IsolateCommand; User=; clientProfileID=; clientSessionID=
2020-07-28T14:21:38:117-04:00 9144 I012C0000: service_process(2184): Command engine: starting at 7/28/2020 2:21:38 PM.
2020-07-28T14:21:38:117-04:00 9144 I00000000: service_process(2184): -- Server is starting --
2020-07-28T14:21:38:148-04:00 9144 I00000000: service_process(2184): Server protocol: '35', identity: '<default>'.
2020-07-28T14:21:38:195-04:00 9144 I02470003: service_process(2184): Curl CA certificates location: 'C:\ProgramData\Acronis\CurlCaCertificates\cert_bundle.pem'
2020-07-28T14:21:38:195-04:00 9144 I02470003: service_process(2184): Curl CA certificates location: 'C:\ProgramData\Acronis\CurlCaCertificates\cert_bundle.pem'
2020-07-28T14:21:38:210-04:00 9144 I00000000: service_process(2184): LazyLoadedComponentManager::InitializeComponents
2020-07-28T14:21:38:257-04:00 9144 I00000000: service_process(2184): Using SecurePersistentCredentialStore.
2020-07-28T14:21:38:414-04:00 9144 I00000000: service_process(2184): -- Server started --
2020-07-28T14:21:38:460-04:00 9560 I0135003A: service_process(2184): User is running command. Command=Running Python script; User=; clientProfileID=; clientSessionID=792FCCDB-95B8-4100-8BE9-C35489223675
2020-07-28T14:21:38:726-04:00 9560 I0135003B: service_process(2184): Command has completed successfully. Command=Running Python script;
2020-07-28T14:21:38:742-04:00 368 I0135003B: Command has completed successfully. Command=Tol::IsolateCommand;
2020-07-28T14:21:40:742-04:00 9144 I00000000: service_process(2184): -- Server is stopping --
2020-07-28T14:21:40:742-04:00 9144 I00000000: service_process(2184): LazyLoadedComponentManager::DeactivateComponents
2020-07-28T14:21:40:742-04:00 9144 I00000000: service_process(2184): LazyLoadedComponentManager::DeinitializeComponents
2020-07-28T14:21:40:757-04:00 9144 I00000000: service_process(2184): -- Server stopped --
2020-07-28T14:22:24:321-04:00 7068 I0135003A: User is running command. Command=Running task '0' of backup plan '94DF079D-5A85-0277-0D76-DEF8706A86C0'; User=REDACTED; clientProfileID=648FFABE-0C4D-253A-2894-EC345369D5C9; clientSessionID=A0516EC2-1A63-4489-84A9-799341032EC9; tenantID=00000000-0000-0000-0000-000000000000
2020-07-28T14:22:54:336-04:00 7068 E0135003D: Error 0x135003d: Command has failed. Command=Running task '0' of backup plan '94DF079D-5A85-0277-0D76-DEF8706A86C0'; tenantID=00000000-0000-0000-0000-000000000000
| trace level: error
| line: 0x4a8728dc8a1c9584
| file: e:\715\enterprise\common\tol\gating_activity.cpp:222
| function: Tol::`anonymous-namespace'::BusinessActivityTracker::OnCompleted
| $module: mms_vsa64_16180
|
| error 0x1350016: TOL: Failed to execute the command. Running task '0' of backup plan '94DF079D-5A85-0277-0D76-DEF8706A86C0'
| line: 0x8d165e86fb819597
| file: e:\715\enterprise\common\tol\command\command.cpp:457
| function: Tol::`anonymous-namespace'::MakeFailResult
| CommandID: 925A3E23-440F-49EC-BB8D-460867C96945
| $module: mms_vsa64_16180
|
| error 0x1350016: TOL: Failed to execute the command. Running task '0' of backup plan '94DF079D-5A85-0277-0D76-DEF8706A86C0'
| line: 0x8d165e86fb819597
| file: e:\715\enterprise\common\tol\command\command.cpp:457
| function: Tol::`anonymous-namespace'::MakeFailResult
| CommandID: 925A3E23-440F-49EC-BB8D-460867C96945
| $module: agent_protection_addon_vsa64_16180
|
| error 0x1490bba: Failed to run task '0' of backup plan '94DF079D-5A85-0277-0D76-DEF8706A86C0'.
| line: 0x8234610504d1e273
| file: e:\715\enterprise\managers\gtob\protection\commands\agent_run_protection\run_by_number.cpp:92
| function: RunProtectionByNumber::`anonymous-namespace'::Command::SafeExecute
| $module: agent_protection_addon_vsa64_16180
|
| error 0x1490fa1: Failed to find a backup plan with ID '94DF079D-5A85-0277-0D76-DEF8706A86C0'.
| line: 0x3fcc71dd91c652a
| file: e:\715\enterprise\managers\gtob\protection\commands\agent_run_protection\utils.cpp:100
| function: Utils::EnsureBackupPlanExists
| $module: agent_protection_addon_vsa64_16180
2020-07-28T14:22:54:352-04:00 368 I0135003A: User is running command. Command=Running task '0' of backup plan '94DF079D-5A85-0277-0D76-DEF8706A86C0'; User=REDACTED; clientProfileID=648FFABE-0C4D-253A-2894-EC345369D5C9; clientSessionID=A0516EC2-1A63-4489-84A9-799341032EC9; tenantID=00000000-0000-0000-0000-000000000000
2020-07-28T14:23:24:368-04:00 368 E0135003D: Error 0x135003d: Command has failed. Command=Running task '0' of backup plan '94DF079D-5A85-0277-0D76-DEF8706A86C0'; tenantID=00000000-0000-0000-0000-000000000000
| trace level: error
| line: 0x4a8728dc8a1c9584
| file: e:\715\enterprise\common\tol\gating_activity.cpp:222
| function: Tol::`anonymous-namespace'::BusinessActivityTracker::OnCompleted
| $module: mms_vsa64_16180
|
| error 0x1350016: TOL: Failed to execute the command. Running task '0' of backup plan '94DF079D-5A85-0277-0D76-DEF8706A86C0'
| line: 0x8d165e86fb819597
| file: e:\715\enterprise\common\tol\command\command.cpp:457
| function: Tol::`anonymous-namespace'::MakeFailResult
| CommandID: 925A3E23-440F-49EC-BB8D-460867C96945
| $module: mms_vsa64_16180
|
| error 0x1350016: TOL: Failed to execute the command. Running task '0' of backup plan '94DF079D-5A85-0277-0D76-DEF8706A86C0'
| line: 0x8d165e86fb819597
| file: e:\715\enterprise\common\tol\command\command.cpp:457
| function: Tol::`anonymous-namespace'::MakeFailResult
| CommandID: 925A3E23-440F-49EC-BB8D-460867C96945
| $module: agent_protection_addon_vsa64_16180
|
| error 0x1490bba: Failed to run task '0' of backup plan '94DF079D-5A85-0277-0D76-DEF8706A86C0'.
| line: 0x8234610504d1e273
| file: e:\715\enterprise\managers\gtob\protection\commands\agent_run_protection\run_by_number.cpp:92
| function: RunProtectionByNumber::`anonymous-namespace'::Command::SafeExecute
| $module: agent_protection_addon_vsa64_16180
|
| error 0x1490fa1: Failed to find a backup plan with ID '94DF079D-5A85-0277-0D76-DEF8706A86C0'.
| line: 0x3fcc71dd91c652a
| function: Utils::EnsureBackupPlanExists
| file: e:\715\enterprise\managers\gtob\protection\commands\agent_run_protection\utils.cpp:100
| $module: agent_protection_addon_vsa64_16180

 

0 Users found this helpful
frestogaslorastaswastavewroviwroclolacorashibushurutraciwrubrishabenichikucrijorejenufrilomuwrigaslowrikejawrachosleratiswurelaseriprouobrunoviswosuthitribrepakotritopislivadrauibretisetewrapenuwrapi
Posts: 0
Comments: 2016

Hello David,

Welcome to Acronis forums!

The error in the activity log that you've shared shows that the backup plan is missed on the Agent. Possibly the root cause is that the Agent was not properly deployed. In this situation I recommend that you do the following:

- delete the old backup plan;

- open Acronis Console -> Settings -> Agents and remove the problem Agent;

- then re-add the Agent again (it can be done by reinstalling the Agent or using the steps described in the following article: https://kb.acronis.com/content/59828);

- after that create a new backup plan and check that it is deployed to the agent (you should be able to see it in the Acronis Console);

- try to run it. 

If the problem still there please collect Acronis System Information from the affected Agent and from Acronis Management Server and contact Acronis Support team.

Hello Maria,

Thanks for your response!

However this did not seem to help and I get the same results. Nothing happening on the server or in the activity log for the job. If using a the local agent for any sort of backup I get this same result.

While attempting your suggestion, I noticed that on all my servers that stopped working when backing up data via local agent they all have a same failed task of "Refreshing Recover Points".

That is the last entry when I check activities from Acronis Console -> Settings -> Agents

Nothing new after that entry is shown so I am thinking this may be somehow related.

Details below for that failed task.

Thanks again for any help.

 

TOL: Failed to execute the command. Refreshing recovery points
Additional info:
------------------------
Error code: 22
Module: 309
LineInfo: 0x8D165E86FB819597
Fields: {"$module":"mms_vsa64_14330","CommandID":"EB4DED5A-19BD-4CB7-8D37-BB2C429B286D"}
Message: TOL: Failed to execute the command. Refreshing recovery points
------------------------
Error code: 22
Module: 309
LineInfo: 0x8D165E86FB819597
Fields: {"$module":"dms_provider_vsa64_14330","CommandID":"EB4DED5A-19BD-4CB7-8D37-BB2C429B286D"}
Message: TOL: Failed to execute the command. Refreshing recovery points
------------------------
Error code: 45
Module: 4
LineInfo: 0xF35F747B3B21F916
Fields: {"$module":"access_manager_vsa64_14330","path":"\\\\?\\C:\\ProgramData\\Acronis\\BackupAndRecovery\\MMS\\AccessVault\\profiles\\02DB5FDA-3D49-2400-0473-DB272D2EBBFE\\lock","function":"OpenFileW"}
Message: The specified file path does not exist.
------------------------
Error code: 65520
Module: 0
LineInfo: 0xBD28FDBD64EDB8F8
Fields: {"$module":"access_manager_vsa64_14330","code":"2147942403"}
Message: The system cannot find the path specified
 
Command has failed. Command=Refreshing recovery points; tenantID=00000000-0000-0000-0000-000000000000
Additional info:
------------------------
Error code: 61
Module: 309
LineInfo: 0x4A8728DC8A1C9584
Fields: {"$module":"mms_vsa64_14330"}
Message: Command has failed. Command=Refreshing recovery points; tenantID=00000000-0000-0000-0000-000000000000
------------------------
Error code: 22
Module: 309
LineInfo: 0x8D165E86FB819597
Fields: {"$module":"mms_vsa64_14330","CommandID":"EB4DED5A-19BD-4CB7-8D37-BB2C429B286D"}
Message: TOL: Failed to execute the command. Refreshing recovery points
------------------------
Error code: 22
Module: 309
LineInfo: 0x8D165E86FB819597
Fields: {"$module":"dms_provider_vsa64_14330","CommandID":"EB4DED5A-19BD-4CB7-8D37-BB2C429B286D"}
Message: TOL: Failed to execute the command. Refreshing recovery points
------------------------
Error code: 45
Module: 4
LineInfo: 0xF35F747B3B21F916
Fields: {"$module":"access_manager_vsa64_14330","path":"\\\\?\\C:\\ProgramData\\Acronis\\BackupAndRecovery\\MMS\\AccessVault\\profiles\\02DB5FDA-3D49-2400-0473-DB272D2EBBFE\\lock","function":"OpenFileW"}
Message: The specified file path does not exist.
------------------------
Error code: 65520
Module: 0
LineInfo: 0xBD28FDBD64EDB8F8
Fields: {"$module":"access_manager_vsa64_14330","code":"2147942403"}
Message: The system cannot find the path specified
frestogaslorastaswastavewroviwroclolacorashibushurutraciwrubrishabenichikucrijorejenufrilomuwrigaslowrikejawrachosleratiswurelaseriprouobrunoviswosuthitribrepakotritopislivadrauibretisetewrapenuwrapi
Posts: 0
Comments: 2016

Hello David.

This error also indicates incorrectly deployed Agents. 

Please follow Solution for error 1 from the respective KB article:

https://kb.acronis.com/content/60667

Hello Maria,

Thanks again for your response.

While this second suggestion did not specifically help me this pointed me in the right direction and I am happy to report that I got all my servers working again!

My issue was that the profile ID folder listed in the error details was actually missing on all the servers. I shut down the MMS service and renamed the existing profile folder to the correct IDs and after restarting the MMS service the backup plans were successfully pushed to the server agents and are now running again.

Thanks again for all your help!