Aller au contenu principal

11.7.50058 cannot validate tape backup

Thread needs solution

Backup windows 10 Version 1607 WS to LTO-2 tape drive.

Backup completes fine, but attempting to validate the archive results in a failure after a random time period between 0 and 5 minutes.  Fails after a different amount of data read from the tape on each validation attempt.   Doing another full BU to another tape and then attempting to validate that fails in exactly the same way.

The tape drive and tape are both in excellent health as far as HP Library and Tape Tools are concerned.  Tape drive full test was run with NO errors.

--------------------
Log Entry Details
--------------------
Type:          Error
Date and time: 24/11/2016 17:39:38
Backup plan:   [None]
Task:          MyValidation_1
Code:          20,250,685(0x135003D)
Module:        309
Owner:         amonra@mercury
Message: Command 'Validating' has failed.
Additional info:
--------------------
Error code: 61
Module: 309
LineInfo: 4a8728dc8a1c9510
Fields:  $module : service_process_vs_50058
Message: Command 'Validating' has failed.
--------------------
Error code: 22
Module: 309
LineInfo: 8d165e86fb819666
Fields:  TraceLevel : 1, $module : disk_bundle_vs_50058
Message: TOL: Failed to execute the command. The 'Validating' command validates an entire backup archive within the specified location.
--------------------
Error code: 208
Module: 161
LineInfo: b320396adfe3c2b
Fields:  IsReturnCode : 1, $module : disk_bundle_vs_50058
Message: Failed to validate the archive.
--------------------
Error code: 110
Module: 161
LineInfo: fa6ac65d19a97885
Fields:  $module : disk_bundle_vs_50058
Message: Failed to validate archive 'Mercury(2016-11-24)'.
--------------------
Error code: 1060
Module: 1
LineInfo: a76a4f127d472868
Fields:  $module : disk_bundle_vs_50058
Message:
--------------------
Error code: 91
Module: 64
LineInfo: bae6e99b422c0e43
Fields:  code : 8, $module : disk_bundle_vs_50058
Message: Failed to validate backup archive 'EEA418AF-1DA3-4D0D-90B5-1C1A0AD638A1' from the location with ID 'atis:/tape?F7117F5F-1D3F-4AED-97B5-938872096DE5'.
--------------------
Acronis Knowledge Base: http://kb.acronis.com/errorcode/

Event code: 0x0135003D+0x01350016+0x00A100D0+0x00A1006E+0x00010424+0x0040005B+0x00000008

--------------------

I've not yet tried to restore the backup to another drive (my next step).

Dave

0 Users found this helpful

Well that was interesting, I tried to restore the partitions to another disk and the last partition from the original disk was restored to the beginning of the new disk instead of the end which is totally insane.  The next partition attempted was the main windows partition and that restore failed eventually after 20 odd minutes:

Here's the log:

<log build="50058" version="11" product="Acronis Backup Management Console">
  <event id="1" type="Information" time="25/11/2016 15:07:32" policy="[None]" task="MyRecover_1" InstanceType="[None]" Instance="[None]" Machine="mercury" message="Command 'Recovering volumes/Managing ASZ' is running." code="20,250,682(0x135003A)" module="309" Owner="amonra@mercury"/>
  <event id="2" type="Information" time="25/11/2016 15:07:33" policy="[None]" task="MyRecover_1" InstanceType="[None]" Instance="[None]" Machine="mercury" message="Data recovery From: &#32;Backup type: Image Recovery of: Disks |Additional info: |--------------------|Error code: 1020|Module: 1|LineInfo: d6766e7a609aabf5|Fields: &#32;$module : disk_bundle_vs_50058|Message: Data recovery From: &#32;Backup type: Image Recovery of: Disks |--------------------" code="66,556(0x103FC)" module="1" Owner="amonra@mercury"/>
  <event id="3" type="Information" time="25/11/2016 15:07:33" policy="[None]" task="MyRecover_1" InstanceType="[None]" Instance="[None]" Machine="mercury" message="Pending operation 49 started: 'Clearing disk (Hard disk 3)'." code="66,040(0x101F8)" module="1" Owner="amonra@mercury"/>
  <event id="4" type="Information" time="25/11/2016 15:07:33" policy="[None]" task="MyRecover_1" InstanceType="[None]" Instance="[None]" Machine="mercury" message="Pending operation 12 started: 'Recovering partition sector by sector (No label () -> No label (F))'." code="66,040(0x101F8)" module="1" Owner="amonra@mercury"/>
  <event id="5" type="Information" time="25/11/2016 15:07:33" policy="[None]" task="MyRecover_1" InstanceType="[None]" Instance="[None]" Machine="mercury" message="Tape 'Tape 1' has been mounted to drive 'HP Ultrium 2-SCSI F63D (HUL6C00989)'." code="4,390,912(0x430000)" module="67" Owner="amonra@mercury"/>
  <event id="6" type="Information" time="25/11/2016 15:07:43" policy="[None]" task="MyRecover_1" InstanceType="[None]" Instance="[None]" Machine="mercury" message="Starting to read from tape 'Tape 1'." code="6,553,600(0x640000)" module="100" Owner="amonra@mercury"/>
  <event id="7" type="Information" time="25/11/2016 15:09:04" policy="[None]" task="MyRecover_1" InstanceType="[None]" Instance="[None]" Machine="mercury" message="Pending operation 12 started: 'Recovering partition sector by sector (Mercury (C) -> Mercury (G))'." code="66,040(0x101F8)" module="1" Owner="amonra@mercury"/>
  <event id="8" type="Error" time="25/11/2016 15:27:03" policy="[None]" task="MyRecover_1" InstanceType="[None]" Instance="[None]" Machine="mercury" message="Operation with partition 'C:' was terminated.||Additional info: |--------------------|Error code: 502|Module: 1|LineInfo: a164035b3ff39285|Fields: &#32;$module : disk_bundle_vs_50058|Message: Operation with partition 'C:' was terminated.||--------------------|Error code: 32|Module: 7|LineInfo: f5f8cbcf76155639|Fields: &#32;$module : disk_bundle_vs_50058|Message: The archive is corrupted.|--------------------" code="66,038(0x101F6)" module="1" Owner="amonra@mercury"/>
  <event id="9" type="Information" time="25/11/2016 15:27:03" policy="[None]" task="MyRecover_1" InstanceType="[None]" Instance="[None]" Machine="mercury" message="Bootability fix operation has started." code="1,900,544(0x1D0000)" module="29" Owner="amonra@mercury"/>
  <event id="10" type="Warning" time="25/11/2016 15:27:03" policy="[None]" task="MyRecover_1" InstanceType="[None]" Instance="[None]" Machine="mercury" message="Multiple partitions with UUID '80C15E0A641ED101' are found.|Additional info: |--------------------|Error code: 823|Module: 29|LineInfo: edccd0da4eb55b46|Fields: &#32;$module : disk_bundle_vs_50058|Message: Multiple partitions with UUID '80C15E0A641ED101' are found.|--------------------" code="1,901,367(0x1D0337)" module="29" Owner="amonra@mercury"/>
  <event id="11" type="Warning" time="25/11/2016 15:27:03" policy="[None]" task="MyRecover_1" InstanceType="[None]" Instance="[None]" Machine="mercury" message="Several partitions have an equal UUID. System boot problems may appear.|Additional info: |--------------------|Error code: 823|Module: 29|LineInfo: edccd0da4eb55d6b|Fields: &#32;$module : disk_bundle_vs_50058|Message: Several partitions have an equal UUID. System boot problems may appear.|--------------------" code="1,901,367(0x1D0337)" module="29" Owner="amonra@mercury"/>
  <event id="12" type="Warning" time="25/11/2016 15:27:03" policy="[None]" task="MyRecover_1" InstanceType="[None]" Instance="[None]" Machine="mercury" message="Disk '\local\hd_sign(2329572C)' has an invalid BIOS number: 0. The following value will be used: 129.|Additional info: |--------------------|Error code: 500|Module: 29|LineInfo: b43bbf466f1c1b3b|Fields: &#32;$module : disk_bundle_vs_50058|Message: Disk '\local\hd_sign(2329572C)' has an invalid BIOS number: 0. The following value will be used: 129.|--------------------" code="1,901,044(0x1D01F4)" module="29" Owner="amonra@mercury"/>
  <event id="13" type="Information" time="25/11/2016 15:27:03" policy="[None]" task="MyRecover_1" InstanceType="[None]" Instance="[None]" Machine="mercury" message="Bootability fix operation has finished." code="1,900,544(0x1D0000)" module="29" Owner="amonra@mercury"/>
  <event id="14" type="Information" time="25/11/2016 15:27:54" policy="[None]" task="MyRecover_1" InstanceType="[None]" Instance="[None]" Machine="mercury" message="Bootability fix operation has started." code="1,900,544(0x1D0000)" module="29" Owner="amonra@mercury"/>
  <event id="15" type="Information" time="25/11/2016 15:27:54" policy="[None]" task="MyRecover_1" InstanceType="[None]" Instance="[None]" Machine="mercury" message="Bootability fix operation has finished." code="1,900,544(0x1D0000)" module="29" Owner="amonra@mercury"/>
  <event id="16" type="Information" time="25/11/2016 15:27:54" policy="[None]" task="MyRecover_1" InstanceType="[None]" Instance="[None]" Machine="mercury" message="Tape drive operation statistics.| &#32;Rewinds count: 0| &#32;Total rewind time: 0 seconds| &#32;Seeks count: 83| &#32;Total seek time: 901 seconds| &#32;Reads count: 3557| &#32;Total read time: 158 seconds| &#32;Total data read: 6866829312 bytes| &#32;Average data read: 1930511 bytes| &#32;Total read interval: 1001 seconds| &#32;Max read interval: 50080 milliseconds| &#32;Average read interval: 281 milliseconds| &#32;Average read speed: 43387351 bytes/second| &#32;Writes count: 0| &#32;Total write time: 0 seconds| &#32;Total data wrote: 0 bytes| &#32;Average data wrote: 0 bytes| &#32;Total write interval: 0 seconds| &#32;Max write interval: 0 milliseconds| &#32;Average write interval: 0 milliseconds| &#32;Average write speed: 0 bytes/second|" code="0(0x0)" module="0" Owner="amonra@mercury"/>
  <event id="17" type="Error" time="25/11/2016 15:27:54" policy="[None]" task="MyRecover_1" InstanceType="[None]" Instance="[None]" Machine="mercury" message="Command 'Recovering volumes/Managing ASZ' has failed.|Additional info: |--------------------|Error code: 61|Module: 309|LineInfo: 4a8728dc8a1c9510|Fields: &#32;$module : service_process_vs_50058|Message: Command 'Recovering volumes/Managing ASZ' has failed.|--------------------|Error code: 22|Module: 309|LineInfo: 8d165e86fb819666|Fields: &#32;TraceLevel : 1, $module : disk_bundle_vs_50058|Message: TOL: Failed to execute the command. Recovering disks and volumes.|--------------------|Error code: 768|Module: 149|LineInfo: ce47d41ec10269fd|Fields: &#32;$module : disk_bundle_vs_50058|Message: Recovery has failed.|--------------------|Error code: 1060|Module: 1|LineInfo: b43e776571144dee|Fields: &#32;$module : disk_bundle_vs_50058|Message: Failed to commit operations.|--------------------|Error code: 32|Module: 7|LineInfo: f5f8cbcf76155639|Fields: &#32;$module : disk_bundle_vs_50058|Message: The archive is corrupted.|--------------------" code="20,250,685(0x135003D)" module="309" Owner="amonra@mercury"/>
</log>

Good thing I'm only trying the product ...

Dave

That was interesting. 

As I couldn't do a stand-alone backup booted from the CD because it's an evaluation copy, I thought I'd try to run a stand-alone validate of the tape backup that I took using the product installed under Windows (that would neither validate nor restore).

It took me a while to work out that I needed to do a "re-scan" to be able to validate that archive.

Once I'd done that however, the entire backup tape validated without a problem, so it looks like the Windows code to read LTO archives is broken as well as the code that restores the partitions to the wrong location.   So far so not impressed.

Dave

Hmmm re-downloaded, reinstalled, retried backup followed by validate and this time it works????

Maybe it was a bad install image?  Though if that was the case, I'm surprised that wasn't detected during the installation.

Unless the latest Windows update fixed something ????

Odd indeed.

"All's well that ends well" as they say.

frestogaslorastaswastavewroviwroclolacorashibushurutraciwrubrishabenichikucrijorejenufrilomuwrigaslowrikejawrachosleratiswurelaseriprouobrunoviswosuthitribrepakotritopislivadrauibretisetewrapenuwrapi
Contributions: 250
Commentaires: 7092

Hello David,

Thank you for feeding back! Should you face any issues with recovery, I'd recommend reporting this to Acronis support team.