Salta al contenuto principale

Data is corrupted?

Thread solved

ATI 2020, build 25700.  Windows 10, v1909 (build 18363.752).

My post-backup script didn't put the NAS to sleep as it did before.  I reviewed the logs.  ti_daemon log says operation successful.  But pcs.0.log says "Data is corrupted".  What corruption ATI was talking about?  Was it the source or the destination?

ti_daemon log:

2020-04-13T09:19:32:756-05:00 16952 I00000000: -----
2020-04-13T09:19:32:756-05:00 16952 I00000000: ATI Demon started. Version: 24.6.1.25700.
2020-04-13T09:19:32:849-05:00 16952 I00640000: Backup reserve copy attributes: format tib; need_reserve_backup_copy false; 
2020-04-13T09:19:32:850-05:00 16952 I00640002: Operation Micron started manually.
2020-04-13T09:19:32:883-05:00 16952 I00640000: Backup reserve copy attributes: format tib; need_reserve_backup_copy false; 
2020-04-13T09:19:32:884-05:00 16952 I013C0000: Operation: Backup
2020-04-13T09:19:32:885-05:00 16952 I0064000B: Priority changed to Low.
2020-04-13T09:20:53:163-05:00 16952 I00010400: Child process has exited with code '0'.
2020-04-13T09:20:53:163-05:00 16952 I00640086: Execution of user command succeeded: C:/Windows/QNAP_On.cmd
2020-04-13T09:20:53:511-05:00 16952 I000B03F0: <bold>Create Backup Archive</bold><endl/><tabpoint value=30><indent value=4>From:    <indent value=10><textcolor value="navyblue">Disk 3</textcolor></indent><indent value=4><endl/>To file:    <indent value=10><textcolor value="navyblue">"nas://QNAP/Backup/Micron.tib"</textcolor></indent><indent value=4><endl/>Compression:    <indent value=10><textcolor value="navyblue">Normal</textcolor></indent><indent value=4><endl/>Exclude:    <indent value=10><textcolor value="navyblue">Files matching mask</textcolor></indent><indent value=4><endl/>Match criterion:    <indent value=10><textcolor value="navyblue">hiberfil.sys, pagefile.sys, $Recycle.Bin, swapfile.sys, System Volume Information, *.tib, *.tib.metadata, *.~, *.tmp, D:\Users\User\AppData\Local\Mozilla\Firefox\Profiles\*\cache2, D:\Users\User\AppData\Local\Mozilla\Firefox\Profiles\*\OfflineCache, D:\Users\User\AppData\Local\Opera Software\Opera Stable\Cache, D:\Users\User\AppData\Local\Opera Software\Opera Stable\Media Cache, D:\Users\User\AppData\Local\Google\Chrome\User Data\Default\Cache, C:\WINDOWS\CSC, D:\VirtualMachines\Lab\, *.vdf, E:\</textcolor></indent><indent value=4><endl/></indent><endl/>
2020-04-13T09:20:53:538-05:00 16952 I000101F8: Pending operation 174 started: 'Creating partition image'.
2020-04-13T09:20:53:748-05:00 16396 I00640000: Writing full version to file: Micron_full_b1_s1_v1.tib
2020-04-13T09:20:56:717-05:00 16952 I000101F8: Pending operation 174 started: 'Creating partition image'.
2020-04-13T09:20:57:142-05:00 16952 I000101F8: Pending operation 174 started: 'Creating partition image'.
2020-04-13T09:30:34:092-05:00 16952 I000101F8: Pending operation 174 started: 'Creating partition image'.
2020-04-13T09:30:42:946-05:00 16952 I000101F8: Pending operation 174 started: 'Creating partition image'.
2020-04-13T10:48:55:516-05:00 16952 I00640000: The following backups have been successfully created:<endl/>"nas://QNAP/Backup/Micron_full_b1_s1_v1.tib"
2020-04-13T10:48:55:688-05:00 16952 I00640084: Starting user command: C:/Windows/QNAP_Off.cmd
2020-04-13T10:48:55:739-05:00 16952 I013C0006: Operation has succeeded.
 

pcs.0.log:

2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='hiberfil.sys', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='pagefile.sys', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='$Recycle.Bin', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='swapfile.sys', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='System Volume Information', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='*.tib', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='*.tib.metadata', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='*.~', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='*.tmp', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='D:/Users/User/AppData/Local/Mozilla/Firefox/Profiles/*/cache2', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='D:/Users/User/AppData/Local/Mozilla/Firefox/Profiles/*/OfflineCache', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='D:/Users/User/AppData/Local/Opera Software/Opera Stable/Cache', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='D:/Users/User/AppData/Local/Opera Software/Opera Stable/Media Cache', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='D:/Users/User/AppData/Local/Google/Chrome/User Data/Default/Cache', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='C:/WINDOWS/CSC', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='D:/VirtualMachines/Lab/', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='*.vdf', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='E:/', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T09:20:53:527-05:00 16260 I00000000: file_filter_add_mask(mask='*/System Volume Information/*{3808876B-C176-4e48-B7AE-04046E6CC752}', mask_kind=FF_MASK_EXCLUDE, mask_type=FF_MASK_WILDCARDS)
2020-04-13T10:48:55:591-05:00 16260 I00000000: ar#1: opening archive path="\\?\UNC\QNAP\Backup\/Micron_full_b1_s1_v1.tib" in readonly mode
2020-04-13T10:48:55:604-05:00 16260 E00000000: ar#1: failed to open the archive: corrupted page is found at 0x703a288000
2020-04-13T10:48:55:604-05:00 16260 E00000000: ar#1: failed to open archive path="\\?\UNC\QNAP\Backup\/Micron_full_b1_s1_v1.tib" mode=readonly uuid=00000000000000000000000000000000, err=-5003 (Data is corrupted: CRC mismatch or internal data structures mismatch)
 

 

0 Users found this helpful

Michael, if you order the log messages to show then in the actual date/time order they were given, then the errors in the PCS log occur after the main ti_demon log has reported the backup as being successful.

2020-04-13T10:48:55:516 The following backups have been successfully created:<endl/>"nas://QNAP/Backup/Micron_full_b1_s1_v1.tib"
2020-04-13T10:48:55:604 ar#1: failed to open the archive: corrupted page is found at 0x703a288000
2020-04-13T10:48:55:604 ar#1: failed to open archive path="\\?\UNC\QNAP\Backup\/Micron_full_b1_s1_v1.tib" mode=readonly uuid=00000000000000000000000000000000, err=-5003 (Data is corrupted: CRC mismatch or internal data structures mismatch)
2020-04-13T10:48:55:688 Starting user command: C:/Windows/QNAP_Off.cmd
2020-04-13T10:48:55:739 Operation has succeeded.

Given you are backing up your Disk3 with 4 partitions using a .tib format archive file, this backup task has been migrated from an older version of ATI otherwise it would be creating a .tibx file.

It looks possible that the error being reported may be related to a validation action that fails.

The PCS log is not normally used for diagnostic purposes for .tib files.  The main logs used with ATI 2020 are the ti_demon and backup_worker logs, where the former is used for all backups but shows reduced information for .tib backups, and the latter is used for .tibx tasks.