Skip to main content

Backup failed

Thread needs solution

Hello. we have a problem with one of our backups.
The backup is to a synology diskstation( fully up-to-date) The problem just started with no visible cause to me. I have rebooted the NAS , virtualAppliance etc , even the VM`s itself.
The .TIB file is about 900MB .
Acronis version is : build 9.0.10007

This is the log file :

Task 'Back up to 'Network'' failed: 'Failed to create a backup.
Additional info:
--------------------
Error code: 3
Module: 435
LineInfo: 555b5abba095034c
Fields:
Message: Failed to create a backup.
--------------------
Error code: 32786
Module: 114
LineInfo: 28314c961de7d337
Fields:
Message: Failed to prepare for backing up.
--------------------
Error code: 353
Module: 149
LineInfo: a71592046cb2c5f6
Fields:
Message: Failed to back up the group.
--------------------
Error code: 2
Module: 218
LineInfo: 338a407ad20e0987
Fields:
Message: Error occurred while running the backup and recovery engine.
--------------------
Error code: 1080
Module: 1
LineInfo: b43e776571144dee
Fields:
Message: Commit of operations result is failed.
--------------------
Error code: 4
Module: 7
LineInfo: 9d2de1f088cbc4d7
Fields:
Message: Failed to perform the operation with archive 'avfs:/smb?//192.168.10.11/GEBOVM/Archive.TIB'. Error: 'Write error.'.
--------------------
Error code: 23
Module: 4
LineInfo: 3d9ea26f1bfdef0a
Fields:
Message: Failed to lock the file.
--------------------
Error code: 65521
Module: 0
LineInfo: bd28fdbd64edb8d0
Fields: code : 13
Message: Permission denied
--------------------

0 Users found this helpful
frestogaslorastaswastavewroviwroclolacorashibushurutraciwrubrishabenichikucrijorejenufrilomuwrigaslowrikejawrachosleratiswurelaseriprouobrunoviswosuthitribrepakotritopislivadrauibretisetewrapenuwrapi
Posts: 22
Comments: 3800

Hi Michel,

Such error may appear if there are simultaneous writes into the same archive from 2 separate backup tasks. In your case it would be still worthy to check it to confirm that there is only 1 task backing up into this archive (are there any other appliances installed in the environment which might possibly lock the file?). Another suspicious thing is the size of the archive: 900MB seem to be too small for any valid production VM. Can this archive be validated (Actions->Validate)? If not then likely this archive has to be re-created due to corruption.

If nothing of the above helps, then we'll need to check the full log from the agent (View->Show Logs->Save All To File) to figure out what might be going wrong.

Thank you.
--
Best regards,
Vasily
Acronis Virtualization Program Manager

I`m sorry the backup size is 900GB , my mistake.
There is only 1 task . i will upload the full log

the full log :

Backup has failed. Please check the log for additional information.
1 Information 10/16/14 11:00:01 PM Task 'Back up to 'Network'' changed its state from 'idle' to 'running'.
2 Information 10/16/14 11:00:01 PM Task 'Back up to 'Network'' was started.
3 Information 10/16/14 11:00:11 PM Backup deletion
4 Information 10/16/14 11:02:55 PM 18 backup(s) have been deleted from archive Archive.TIB.
5 Information 10/16/14 11:02:55 PM Backup deletion has successfully completed.
6 Information 10/16/14 11:02:55 PM Backing up VM(s).
7 Information 10/16/14 11:02:56 PM Successfully connected to 'VMware ESXi 5.5.0 build-1623387'.
8 Information 10/16/14 11:03:01 PM Successfully connected to 'VMware ESXi 5.5.0 build-1623387'.
9 Information 10/16/14 11:06:38 PM Meta collection started for application: Microsoft Exchange Server
10 Information 10/16/14 11:08:26 PM Collected meta backup started for application: Microsoft Exchange Server
11 Information 10/16/14 11:09:21 PM Creating snapshot (10/16/14 11:08:27 PM).
12 Information 10/16/14 11:09:21 PM Loading virtual machine 'vm://C243722E-6EE3-9319-AAA4-333A88C8FFFF/52542a09-01ff-1227-8b8a-a8555fc3a06c?host=ha-host&type=vmwesx' into the disk subsystem.
13 Information 10/16/14 11:09:22 PM Adding virtual hard disk '[datastore2] Exchange Server/Exchange Server-000002.vmdk' to '1:1'.
14 Information 10/16/14 11:09:28 PM Analyzing partition 'C:'...
15 Information 10/16/14 11:09:28 PM Analyzing partition 'D:'...
16 Information 10/16/14 11:10:23 PM Preparing for the Microsoft Exchange database validation.
17 Information 10/16/14 11:10:23 PM Enumerating Microsoft Exchange database files.
18 Information 10/16/14 11:10:55 PM Analyzing partition '1-0'...
19 Information 10/16/14 11:11:17 PM Create Incremental Backup From: Disk '1' To file: "avfs:/smb?//192.168.10.11/GEBOVM/Archive.TIB" Compression: Normal Exclude: Files matching mask Match criterion:
20 Information 10/16/14 11:11:17 PM Backing up additional file 'vss_backup_components.xml'.
21 Information 10/16/14 11:11:17 PM Backing up additional file 'vss_writer_metadata1.xml'.
22 Information 10/16/14 11:11:17 PM Backing up additional file 'vss_writer_metadata10.xml'.
23 Information 10/16/14 11:11:17 PM Backing up additional file 'vss_writer_metadata11.xml'.
24 Information 10/16/14 11:11:17 PM Backing up additional file 'vss_writer_metadata12.xml'.
25 Information 10/16/14 11:11:17 PM Backing up additional file 'vss_writer_metadata13.xml'.
26 Information 10/16/14 11:11:17 PM Backing up additional file 'vss_writer_metadata14.xml'.
27 Information 10/16/14 11:11:17 PM Backing up additional file 'vss_writer_metadata2.xml'.
28 Information 10/16/14 11:11:17 PM Backing up additional file 'vss_writer_metadata3.xml'.
29 Information 10/16/14 11:11:17 PM Backing up additional file 'vss_writer_metadata4.xml'.
30 Information 10/16/14 11:11:17 PM Backing up additional file 'vss_writer_metadata5.xml'.
31 Information 10/16/14 11:11:17 PM Backing up additional file 'vss_writer_metadata6.xml'.
32 Information 10/16/14 11:11:17 PM Backing up additional file 'vss_writer_metadata7.xml'.
33 Information 10/16/14 11:11:17 PM Backing up additional file 'vss_writer_metadata8.xml'.
34 Information 10/16/14 11:11:17 PM Backing up additional file 'vss_writer_metadata9.xml'.
35 Information 10/16/14 11:11:17 PM Backing up additional file 'Exchange Server-000001.vmdk'.
36 Information 10/16/14 11:11:17 PM Backing up additional file 'Exchange Server-000002.vmdk'.
37 Information 10/16/14 11:11:17 PM Backing up additional file 'Exchange Server.nvram'.
38 Information 10/16/14 11:11:17 PM Backing up additional file 'Exchange Server.vmdk'.
39 Information 10/16/14 11:11:18 PM Backing up additional file 'Exchange Server.vmx'.
40 Information 10/16/14 11:11:18 PM Backing up additional file 'Exchange Server.vmxf'.
41 Information 10/16/14 11:11:18 PM Backing up additional file 'vss_manifests.zip'.
42 Information 10/16/14 11:11:18 PM Backing up additional file '9127E80B-C6C3-4bbf-817D-517E4CE428FC_bunch_1_rank_1_meta.sqlite'.
43 Information 10/16/14 11:11:18 PM Backing up additional file '9127E80B-C6C3-4bbf-817D-517E4CE428FC_bunch_1_rank_2_meta.sqlite'.
44 Information 10/16/14 11:11:18 PM Backing up additional file '9127E80B-C6C3-4bbf-817D-517E4CE428FC_bunch_1_rank_3_blob'.
45 Information 10/16/14 11:11:18 PM Backing up additional file '9127E80B-C6C3-4bbf-817D-517E4CE428FC_bunch_1_rank_3_meta.sqlite'.
46 Information 10/16/14 11:11:18 PM Backing up additional file '9127E80B-C6C3-4bbf-817D-517E4CE428FC_sqlite_ranked_meta.sqlite'.
47 Information 10/16/14 11:11:18 PM Backing up additional file 'strids.xml'.
48 Information 10/16/14 11:11:25 PM Pending operation 153 started: 'Creating partition image'.
49 Information 10/16/14 11:12:00 PM Retrying SOAP error '12'
50 Information 10/16/14 11:12:03 PM Retrying SOAP error '12'
51 Information 10/16/14 11:12:07 PM Pending operation 153 started: 'Creating partition image'.
52 Information 10/16/14 11:18:25 PM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
53 Information 10/16/14 11:21:55 PM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
54 Information 10/16/14 11:24:55 PM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
55 Information 10/16/14 11:28:55 PM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
56 Information 10/16/14 11:30:55 PM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
57 Information 10/16/14 11:35:55 PM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
58 Information 10/16/14 11:43:26 PM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
59 Information 10/16/14 11:45:55 PM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
60 Information 10/16/14 11:50:56 PM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
61 Information 10/16/14 11:52:55 PM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
62 Information 10/16/14 11:54:56 PM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
63 Information 10/16/14 11:57:55 PM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
64 Information 10/16/14 11:59:25 PM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
65 Information 10/17/14 12:00:55 AM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
66 Information 10/17/14 12:03:26 AM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
67 Information 10/17/14 12:06:55 AM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
68 Information 10/17/14 12:10:25 AM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
69 Information 10/17/14 12:13:25 AM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
70 Information 10/17/14 12:14:55 AM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
71 Information 10/17/14 12:16:56 AM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
72 Information 10/17/14 12:18:56 AM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
73 Information 10/17/14 12:22:25 AM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
74 Information 10/17/14 12:23:57 AM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
75 Information 10/17/14 12:25:55 AM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
76 Information 10/17/14 12:29:25 AM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
77 Information 10/17/14 12:29:54 AM Pending operation 150 started: 'Saving partition structure'.
78 Information 10/17/14 12:30:58 AM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
79 Information 10/17/14 12:31:55 AM Detaching hard disk 'SCSI1:1'.
80 Information 10/17/14 12:32:02 AM Remove snapshot (7-snapshot-38).
81 Information 10/17/14 12:32:32 AM Backup Archive Validation Location: avfs:/smb?//192.168.10.11/GEBOVM/Archive.TIB Archive Type: Image Archive created: Sep 17, 2014 10:10:08 PM
82 Information 10/17/14 12:32:32 AM Pending operation 3 started: 'Validate Backup Archive'.
83 Information 10/17/14 1:06:08 AM Archive validation has successfully completed.
84 Information 10/17/14 1:06:08 AM Successfully connected to 'VMware ESXi 5.5.0 build-1623387'.
85 Information 10/17/14 1:07:03 AM Meta collection started for application: Active Directory
86 Information 10/17/14 1:07:03 AM Collected meta backup started for application: Active Directory
87 Information 10/17/14 1:07:58 AM Creating snapshot (10/17/14 1:07:04 AM).
88 Information 10/17/14 1:07:58 AM Loading virtual machine 'vm://C243722E-6EE3-9319-AAA4-333A88C8FFFF/52b88412-e5d3-34a5-40d4-cf7100b2bc23?host=ha-host&type=vmwesx' into the disk subsystem.
89 Information 10/17/14 1:07:58 AM Adding virtual hard disk '[datastore1] DCSERVER/DCSERVER-000002.vmdk' to '1:1'.
90 Information 10/17/14 1:08:05 AM Analyzing partition 'C:'...
91 Information 10/17/14 1:08:05 AM Analyzing partition 'D:'...
92 Information 10/17/14 1:08:24 AM Analyzing partition '1-0'...
93 Information 10/17/14 1:08:24 AM Create Incremental Backup From: Disk '1' To file: "avfs:/smb?//192.168.10.11/GEBOVM/Archive.TIB" Compression: Normal Exclude: Files matching mask Match criterion:
94 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_backup_components.xml'.
95 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_writer_metadata1.xml'.
96 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_writer_metadata10.xml'.
97 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_writer_metadata11.xml'.
98 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_writer_metadata12.xml'.
99 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_writer_metadata13.xml'.
100 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_writer_metadata14.xml'.
101 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_writer_metadata15.xml'.
102 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_writer_metadata16.xml'.
103 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_writer_metadata17.xml'.
104 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_writer_metadata2.xml'.
105 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_writer_metadata3.xml'.
106 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_writer_metadata4.xml'.
107 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_writer_metadata5.xml'.
108 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_writer_metadata6.xml'.
109 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_writer_metadata7.xml'.
110 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_writer_metadata8.xml'.
111 Information 10/17/14 1:08:24 AM Backing up additional file 'vss_writer_metadata9.xml'.
112 Information 10/17/14 1:08:24 AM Backing up additional file 'DCSERVER-000001.vmdk'.
113 Information 10/17/14 1:08:24 AM Backing up additional file 'DCSERVER-000002.vmdk'.
114 Information 10/17/14 1:08:24 AM Backing up additional file 'DCSERVER.nvram'.
115 Information 10/17/14 1:08:24 AM Backing up additional file 'DCSERVER.vmdk'.
116 Information 10/17/14 1:08:24 AM Backing up additional file 'DCSERVER.vmx'.
117 Information 10/17/14 1:08:25 AM Backing up additional file 'DCSERVER.vmxf'.
118 Information 10/17/14 1:08:25 AM Backing up additional file 'vss_manifests.zip'.
119 Information 10/17/14 1:08:25 AM Backing up additional file '82AD566D-4544-4341-83E8-BFBEF2847028_ad'.
120 Information 10/17/14 1:08:28 AM Pending operation 153 started: 'Creating partition image'.
121 Information 10/17/14 1:08:30 AM Forced sector-by-sector mode.
Additional info:
--------------------
Error code: 46
Module: 7
LineInfo: a5695862aaf8e775
Fields: PartitionId : 397
Message: Forced sector-by-sector mode.
--------------------
Error code: 24
Module: 7
LineInfo: ef8b1618a4c0dd9c
Fields:
Message: MFT bitmap is corrupted.
--------------------
122 Information 10/17/14 1:08:30 AM Retrying SOAP error '12'
123 Information 10/17/14 1:08:33 AM Retrying SOAP error '12'
124 Information 10/17/14 1:08:37 AM Pending operation 153 started: 'Creating partition image'.
125 Information 10/17/14 1:09:55 AM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.
126 Information 10/17/14 1:09:56 AM Failed to lock the file.
Additional info:
--------------------
Error code: 23
Module: 4
LineInfo: 3d9ea26f1bfdef0a
Fields:
Message: Failed to lock the file.
--------------------
Error code: 65521
Module: 0
LineInfo: bd28fdbd64edb8d0
Fields: code : 13
Message: Permission denied
--------------------
127 Error 10/17/14 1:09:56 AM Cannot perform this operation in quiet mode.
Additional info:
--------------------
Error code: 1031
Module: 1
LineInfo: fdc1cbdf2c2335cd
Fields:
Message: Cannot perform this operation in quiet mode.
--------------------
Error code: 23
Module: 4
LineInfo: fdc1cbdf2c2335ce
Fields:
Message: Failed to lock the file. Permission denied
--------------------
128 Error 10/17/14 1:09:57 AM Failed to perform the operation with archive 'avfs:/smb?//192.168.10.11/GEBOVM/Archive.TIB'. Error: 'Write error.'.
Additional info:
--------------------
Error code: 4
Module: 7
LineInfo: 9d2de1f088cbc4d7
Fields:
Message: Failed to perform the operation with archive 'avfs:/smb?//192.168.10.11/GEBOVM/Archive.TIB'. Error: 'Write error.'.
--------------------
Error code: 23
Module: 4
LineInfo: 3d9ea26f1bfdef0a
Fields:
Message: Failed to lock the file.
--------------------
Error code: 65521
Module: 0
LineInfo: bd28fdbd64edb8d0
Fields: code : 13
Message: Permission denied
--------------------
129 Error 10/17/14 1:09:57 AM The operation 'Backing up' has failed.
Additional info:
--------------------
Error code: 26
Module: 100
LineInfo: 48afbd3608a410ca
Fields:
Message: The operation 'Backing up' has failed.
--------------------
Error code: 1080
Module: 1
LineInfo: b43e776571144dee
Fields:
Message: Commit of operations result is failed.
--------------------
Error code: 4
Module: 7
LineInfo: 9d2de1f088cbc4d7
Fields:
Message: Failed to perform the operation with archive 'avfs:/smb?//192.168.10.11/GEBOVM/Archive.TIB'. Error: 'Write error.'.
--------------------
Error code: 23
Module: 4
LineInfo: 3d9ea26f1bfdef0a
Fields:
Message: Failed to lock the file.
--------------------
Error code: 65521
Module: 0
LineInfo: bd28fdbd64edb8d0
Fields: code : 13
Message: Permission denied
--------------------
130 Information 10/17/14 1:09:58 AM Detaching hard disk 'SCSI1:1'.
131 Information 10/17/14 1:10:04 AM Remove snapshot (6-snapshot-37).
132 Information 10/17/14 1:10:04 AM Backup Archive Validation Location: avfs:/smb?//192.168.10.11/GEBOVM/Archive.TIB Archive Type: Image Archive created: Sep 17, 2014 10:10:08 PM
133 Information 10/17/14 1:10:04 AM Pending operation 3 started: 'Validate Backup Archive'.
134 Information 10/17/14 1:39:38 AM Archive validation has successfully completed.
135 Information 10/17/14 1:39:38 AM Task 'Back up to 'Network'' changed its state from 'running' to 'idle'.
136 Error 10/17/14 1:39:38 AM Task 'Back up to 'Network'' failed: 'Failed to create a backup.
Additional info:
--------------------
Error code: 3
Module: 435
LineInfo: 555b5abba095034c
Fields:
Message: Failed to create a backup.
--------------------
Error code: 32786
Module: 114
LineInfo: 28314c961de7d337
Fields:
Message: Failed to prepare for backing up.
--------------------
Error code: 353
Module: 149
LineInfo: a71592046cb2c5f6
Fields:
Message: Failed to back up the group.
--------------------
Error code: 2
Module: 218
LineInfo: 338a407ad20e0987
Fields:
Message: Error occurred while running the backup and recovery engine.
--------------------
Error code: 1080
Module: 1
LineInfo: b43e776571144dee
Fields:
Message: Commit of operations result is failed.
--------------------
Error code: 4
Module: 7
LineInfo: 9d2de1f088cbc4d7
Fields:
Message: Failed to perform the operation with archive 'avfs:/smb?//192.168.10.11/GEBOVM/Archive.TIB'. Error: 'Write error.'.
--------------------
Error code: 23
Module: 4
LineInfo: 3d9ea26f1bfdef0a
Fields:
Message: Failed to lock the file.
--------------------
Error code: 65521
Module: 0
LineInfo: bd28fdbd64edb8d0
Fields: code : 13
Message: Permission denied
--------------------'.

frestogaslorastaswastavewroviwroclolacorashibushurutraciwrubrishabenichikucrijorejenufrilomuwrigaslowrikejawrachosleratiswurelaseriprouobrunoviswosuthitribrepakotritopislivadrauibretisetewrapenuwrapi
Posts: 22
Comments: 3800

Hi Michel,

Thank you for the provided information. According to the log there are multiple network disconnect events which indicates unstable connection from Acronis appliance to the backup location (\\192.168.10.11\):

54 Information 10/16/14 11:24:55 PM The operation was interrupted due to a problem with network connection to the required file. Attempting to ping for 30 seconds.

There is a limited number of re-try attempts when trying to reconnect to the backup storage. This limit is defined in the Backup Options (4th step of the backup wizard)->Error Handling->"Re-attempt the data transmission if network error occurs" and by default it's 30 re-attempts, while in your case it looks like that this limit is reached which causes the task to fail. It makes sense to increase the number of re-attempts to 300 for example and re-try the backup operation. Also you should check the connectivity between the appliance (the virtual switch it is connected to in ESXi host) and the actual backup location. Probably there is some kind of firewall or hardware-related issue in between.

Thank you.
--
Best regards,
Vasily
Acronis Virtualization Program Manager