Exchange 2010 backup jobs failing to run

My Exchange 2010 backup jobs periodically fail. The full backup always succeeds and usually 1 or 2 incrementals do also, but then I start getting the errors below.
Backup has failed. Please check the log for additional information.
1 Information 5/24/2012 11:45:02 PM Backing up a group of VMs.
2 Information 5/24/2012 11:45:02 PM Successfully connected to 'VMware ESXi 5.0.0 build-469512'.
3 Information 5/24/2012 11:45:02 PM Task 'Back up to USB Drives' changed its state from 'idle' to 'running'.
4 Information 5/24/2012 11:45:02 PM Task 'Back up to USB Drives' was started.
5 Information 5/24/2012 11:45:12 PM Successfully connected to 'VMware ESXi 5.0.0 build-469512'.
6 Information 5/24/2012 11:48:45 PM Creating snapshot (5/24/2012 11:45:12 PM).
7 Information 5/24/2012 11:48:48 PM Load virtual machine 'vm://908FE039-E373-BDA7-5291-38D5132C3527/564d00c8-f58b-60fe-e7bb-fca1b22a9bfd?host=ha-host&type=vmwesx' into the disk subsystem.
8 Information 5/24/2012 11:48:48 PM VMware_VDDK: SSLVerifyIsEnabled: failed to open the product registry key. Falling back to default behavior: verification off. LastError = 0
9 Information 5/24/2012 11:48:48 PM VMware_VDDK: SSL_VerifyX509: Certificate verification is disabled, so connection will proceed despite the error
10 Information 5/24/2012 11:48:50 PM VMware_VDDK: SSLVerifyIsEnabled: failed to open the product registry key. Falling back to default behavior: verification off. LastError = 0
11 Information 5/24/2012 11:48:50 PM VMware_VDDK: SSL_VerifyX509: Certificate verification is disabled, so connection will proceed despite the error
12 Information 5/24/2012 11:48:51 PM Analyzing partition 'C:'...
13 Information 5/24/2012 11:48:51 PM Analyzing partition 'D:'...
14 Information 5/24/2012 11:48:53 PM Analyzing partition '2-0'...
15 Information 5/24/2012 11:48:54 PM Analyzing partition '2-0'...
16 Information 5/24/2012 11:48:55 PM Create Incremental Backup From: Disk '1', Disk '2'To file: "avfs:/local?F:/Archive.TIB"Compression: HighExclude: Files matching maskMatch criterion: pagefile.sys, hiberfil.sys; ...
17 Information 5/24/2012 11:48:55 PM Backing up additional file 'SRV01-1_1-000001.vmdk'.
18 Information 5/24/2012 11:48:55 PM Backing up additional file 'SRV01-1_1-000002.vmdk'.
19 Information 5/24/2012 11:48:55 PM Backing up additional file 'SRV01-1_1.nvram'.
20 Information 5/24/2012 11:48:55 PM Backing up additional file 'SRV01-1_1.vmdk'.
21 Information 5/24/2012 11:48:55 PM Backing up additional file 'SRV01-1_1.vmx'.
22 Information 5/24/2012 11:48:55 PM Backing up additional file 'SRV01-1_1.vmxf'.
23 Information 5/24/2012 11:48:55 PM Backing up additional file 'SRV01-1_1_1-000001.vmdk'.
24 Information 5/24/2012 11:48:55 PM Backing up additional file 'SRV01-1_1_1-000002.vmdk'.
25 Information 5/24/2012 11:48:56 PM Backing up additional file 'SRV01-1_1_1.vmdk'.
26 Information 5/24/2012 11:48:56 PM Backing up additional file 'vss_manifests.zip'.
27 Information 5/24/2012 11:49:04 PM Pending operation 153 started: 'Creating partition image'.
28 Information 5/24/2012 11:50:41 PM CBT (Changed Block Tracking) information has been successfully retrieved.
29 Information 5/25/2012 12:03:26 AM Pending operation 153 started: 'Creating partition image'.
30 Information 5/25/2012 12:06:07 AM CBT (Changed Block Tracking) information has been successfully retrieved.
31 Information 5/25/2012 12:34:05 AM Pending operation 150 started: 'Saving partition structure'.
32 Information 5/25/2012 12:34:07 AM Backup Archive ValidationLocation: avfs:/local?F:/Archive.TIBArchive Type: ImageArchive created: Saturday, May 05, 2012 10:54:35 PM
33 Information 5/25/2012 12:34:07 AM Pending operation 3 started: 'Validate Backup Archive'.
34 Information 5/25/2012 12:34:07 AM Remove snapshot (43-snapshot-129).
35 Information 5/25/2012 3:14:48 AM Archive validation has successfully completed.
36 Information 5/25/2012 3:14:48 AM Successfully connected to 'VMware ESXi 5.0.0 build-469512'.
37 Information 5/25/2012 3:15:05 AM Backup Archive ValidationLocation: avfs:/local?F:/Archive.TIBArchive Type: ImageArchive created: Saturday, May 05, 2012 10:54:35 PM
38 Information 5/25/2012 3:15:05 AM Pending operation 3 started: 'Validate Backup Archive'.
39 Information 5/25/2012 5:55:50 AM Archive validation has successfully completed.
40 Information 5/25/2012 5:55:55 AM Task 'Back up to USB Drives' changed its state from 'running' to 'idle'.
41 Error 5/25/2012 5:55:55 AM Task 'Back up to USB Drives' failed: 'Failed to create a backup.
Additional info:
--------------------
Error code: 3
Module: 435
LineInfo: 555b5abba09500ab
Fields: $module : C:\Program Files (x86)\Acronis\vmProtect\Windows Agent\vmms.exe
Message: Failed to create a backup.
--------------------
Error code: 32786
Module: 114
LineInfo: 28314c961de7d3b2
Fields:
Message: Failed to prepare for backing up.
--------------------
Error code: 353
Module: 149
LineInfo: a71592046cb2c5f8
Fields:
Message: Failed to back up the group.
--------------------
Error code: 2
Module: 218
LineInfo: cd07f9d978d3a2ac
Fields:
Message: Error occurred while running the backup and recovery engine.
--------------------
Error code: 239
Module: 83
LineInfo: b7e5b06ccbf4eabe
Fields: $module : C:\Program Files (x86)\Common Files\Acronis\vmProtectAgent\vmProtectService.exe
Message: Failed to execute agent 'exchange_backup_agent.exe' with parameters '{ --set-backup-type, full }'.
--------------------
Error code: 240
Module: 83
LineInfo: c6cba1a4f8372cb5
Fields:
Message: Failed to run script 'cd "C:\Users\ADMINI~1\AppData\Local\Temp\9DB391BD-E22A-470B-B19F-D617050B81C6\" && exchange_backup_agent.exe "--set-backup-type" "full">AF3986C1-DDB3-4FB6-8937-73DD83F84346'.
--------------------
Error code: 163
Module: 83
LineInfo: bc190fbcd6b29612
Fields: ErrorCode : 13, $module : C:\Program Files (x86)\Common Files\Acronis\VixApi\vixapi_server.exe
Message: You do not have access rights to this file --------------------'.

- Se connecter pour poster des commentaires

Hi Sascha,
The error quoted by Andrew had its roots in interaction between the ESXi host and VMware tools inside the backed up VM (to collect Exchange meta-data). Typically such problems are related to problems with ESXi state (quite often ESXi host reboot resolves them). In your case it might be something different. Can you please post the actual errors that you get when trying to back up a VM which is running off a snapshot so that I can analyze it properly?
Thank you.
--
Best regards,
Vasily
Acronis vmProtect Program Manager
- Se connecter pour poster des commentaires

Hi Vasily
Thank you for the detailed explanation.
You finde the errordetails an the end of this message.
I've tryed with different users for the Acronis vCenter integration - all with the same eror-results (the service account for the vCenter service, the service account for the Acronis Windows Agent for VMware).
We use a "Acronis Windows Agent for VMware" to backup the VMs on our ESXi5.1 Hosts.
Best regards,
Sascha
--------------------
Log Entry Details
--------------------
Type: Error
Date and time: 15.02.2013 00:14:00
Backup plan: VMs_XXXXXXXXXXXXXXXX_tACR21
Task: Daily backup
Code: 7'503'956(0x728054)
Module: 114
Owner: svc_XXXXXXXXXXXXX@INXXXXXXXX
Message:
Failed to back up virtual machine 'SPWXXXXXXXX (XXXXXXXXXXXXX- Produktion 1)'.
Additional info:
--------------------
Error code: 32852
Module: 114
LineInfo: c35c04057128fe3d
Fields: $module : disk_bundle_vs_32308
Message: Failed to back up virtual machine 'SPWXXXXXXXXXX (XXXXXXXXXXXXXXX- Produktion 1)'.
--------------------
Error code: 32786
Module: 114
LineInfo: 28314c961de7d2b1
Fields: $module : disk_bundle_vs_32308
Message: Failed to prepare for backing up.
--------------------
Error code: 3
Module: 329
LineInfo: 1cd98aae889424f9
Fields: $module : disk_bundle_vs_32308
Message: Backup has failed.
--------------------
Error code: 1060
Module: 1
LineInfo: d1ab7fa1e56ec6c7
Fields: $module : disk_bundle_vs_32308
Message:
--------------------
Error code: 13
Module: 149
LineInfo: d1ab7fa1e56ec82d
Fields: $module : disk_bundle_vs_32308
Message: Failed to perform the requested operation.
--------------------
Error code: 236
Module: 16
LineInfo: 7bb42efe3c733241
Fields: $module : disk_bundle_vs_32308
Message: Internal error: Attempt to access emulated disk for non-initialized object.
--------------------
Error code: 23
Module: 83
LineInfo: c61573f663f5d900
Fields: $module : esx_srv_vs_32308
Message: Failed to get access to hard disk '[XXXXXX_Disk9] SPWXXXXXXXXXX/SPWXXXXXXXXXX_1-000003.vmdk'.
--------------------
Error code: 94
Module: 83
LineInfo: c61573f663f5d8ba
Fields: $module : esx_srv_vs_32308
Message: Failed to open virtual disk file '[XXXXXX_Disk9] SPWXXXXXXXXXX/SPWXXXXXXXXXX_1-000003.vmdk'.
--------------------
Error code: 13
Module: 0
LineInfo: c61573f663f5d780
Fields: $module : esx_srv_vs_32308
Message: You do not have access rights to this file
--------------------
Acronis Knowledge Base: http://kb.acronis.com/errorcode/
Event code: 0x00728054+0x00728012+0x01490003+0x00010424+0x0095000D+0x001000EC+0x00530017+0x0053005E+0x0000000D
--------------------
- Se connecter pour poster des commentaires

Hi Sascha,
As far as I can see you are using Acronis Backup and Recovery product with Windows Agent for vSphere component. While this is not the exactly right forum branch to create this topic (you should create a new topic here: http://forum.acronis.com/forums/acronis-business-products-discussions/a… ), I can suggest you a possible workaround (the error looks like a logical error where disks are accessed in LAN-free mode):
1) Rename the C:\Program Files (x86)\Common Files\Acronis\VirtualWare\VixDiskLib\plugins\ folder on the machine where you have installed Windows Agent for vSphere into some other name (plugins_old for example) and retry the backup operation. This action will disable LAN-free mode (direct SAN access) and will enable more extensive logging. If this method doesn't work then I'd recommended to contact Acronis support regarding this issue since it does look like a product problem (logical error).
2) Another possible workaround is to use Virtual Appliance (you can deploy it from Acronis Backup and Recovery Management console connected to Acronis Management Server component) instead of Windows Agent for vSphere. This is again a workaround and even if this works, it would be still worthy to contact Acronis support.
Thank you.
--
Best regards,
Vasily
Acronis vmProtect Program Manager
- Se connecter pour poster des commentaires

Many thanks
I will follow your suggestion with the plugins and open also a support call.
Best regards,
Sascha
- Se connecter pour poster des commentaires

Hi Vasily
With the disabled VixDiskLib\Plugins, the backup works (including self cerated snapshots on VMs).
Never get so a fast and qualified response on my problems from Acronis (including official support).
I think, in the future I post my problems always here ;-)
So I will open a support request and give your workaround as input.
Nice weekend,
Sascha
- Se connecter pour poster des commentaires

Thanks for giving the quick qork around here Sascha. It's very much appreciated.
- Se connecter pour poster des commentaires