Unchanged files being archived

Yusuf Pisan
Jan 14, 2012 @ 4:57 am

Large number of files that are already on the destination are being archived every time I run CCC.

I noticed that the copy on the destination has a modified date of "today" while the files on the source have modified dates from 2011. The archived versions also have a modification date of today. Most archived files, maybe all but not sure, that suffer this problem are picture and video files. I have saved this task as a "Scheduled Task" to be run manually, so I can make sure none of the setting are being modified between runs. The "Description of the task" as well as a recent log is included below.

The main problem I think is that some files get copied to destination with the wrong modified date.

I tried deleting one of these files from the destination. Ran CCC and the file got copied from the source with the correct "modified" date. I re-ran CCC and the same file got archived yet again although the "modified" date on the destination is now the same as the source. The archived versions have a modified date of "today".

When I look into my different pictures folders in the destination, about one in four file has a modified date of "today".

If I enable "Calculate a checksum" option, these files are not copied, but then the backup/comparison takes forever.

I look forward to any suggestions. If I cannot resolve this problem then CCC is not usable for me.

Thank you,

Yusuf

==================== Carbon Copy Cloner v. 3.4.3 (396): 2012-01-14 13:46:51 +1100 ====================

OS: Version 10.6.8 (Build 10K549)
Architecture: i386
Mac model: MacBookPro7,1
Number of CPUs: 2
CPU Speed: 2.66 GHz
Memory: 4 GB
Console user id: 501
CCC euid: 0
Task owner: yusufpisan (501)

Task: YP to Sea1 Folder (651553F3-A73F-40A3-9863-9D9602DA4E11)
Source: yusufpisan

Source path: /Users/yusufpisan
Mount point: /
Filesystem: hfs
Capacity: 210.45 GB
Used: 148.15 GB
Available: 62.31 GB
Mac OS X version: 10.6.8
UUID: D2010858-2590-3A8E-B1EA-CD07DCFF61A7
Device ID: /dev/disk0s2
Device vendor: Unidentified Vendor
Device model: Hitachi HTS545032B9SA02                 
Device interface: SATA
Partition format: IOGUIDPartitionScheme
Filesystem owner: 0

Destination: jaculusMacPro

Destination path: /Volumes/Sea1TB/jaculusMacPro
Mount point: /Volumes/Sea1TB
Filesystem: hfs
Capacity: 999.86 GB
Used: 105.87 GB
Available: 893.99 GB
Mac OS X version: Mac OS not installed
UUID: F7DCA05F-5009-33C9-8899-EBAFA1516EEF
Device ID: /dev/disk2s2
Device vendor: Seagate
Device model: Desktop
Device interface: USB
Partition format: IOGUIDPartitionScheme
Filesystem owner: 0

Settings

Archive deleted items, owner: yusufpisan
Archive modified items
Do not automatically prune archives

01/14 13:46:51 "Sea1TB" has ownership enabled.
01/14 13:46:51 The destination volume has Access Control Lists enabled.
01/14 13:46:51 Preparing...
01/14 13:46:51 Authenticating...
01/14 13:46:51 [INFO] Volume mounted: "/Volumes/Sea1TB"
01/14 13:46:51 [INFO] Volume mounted: "/Volumes/c"
01/14 13:46:51 [INFO] Volume mounted: "/"
01/14 13:46:51 [INFO] Volume mounted: "/Volumes/BOOTCAMP"
01/14 13:46:52 Spotlight state on destination: On
01/14 13:46:59 Archive Manager: Creating folder at /Volumes/Sea1TB/jaculusMacPro/_CCC Archives/2012-01-14 (January 14) 13-46-53
01/14 13:47:00 Initiating synchronization engine...
01/14 13:47:00 [sender] Fileflags mask for /Users/yusufpisan/: 0 (10001)
01/14 13:47:00 [receiver] Fileflags mask for /Volumes/Sea1TB/jaculusMacPro: 0 (10001)
01/14 13:47:01 Building a list of items to be considered for backup
01/14 13:47:55 Preparing to copy, total items to consider: 45869
01/14 13:47:55 Build time: 54.089
01/14 13:47:55 Comparing selected items on the source and destination...
01/14 14:22:58 [INFO] Volume mounted: "/Volumes/c-1"
01/14 14:22:58 [INFO] Volume mounted: "/Volumes/c-1"
01/14 14:23:17 [INFO] Volume mounted: "/Volumes/TC Jaculus Backups"
01/14 14:23:17 [INFO] Volume mounted: "/Volumes/TC Jaculus Backups"
01/14 14:24:05 [INFO] Volume unmounted: "TC Jaculus Backups"
01/14 14:24:05 [INFO] Volume unmounted: "TC Jaculus Backups"
01/14 14:24:05 [INFO] Volume unmounted: "c"
01/14 14:24:05 [INFO] Volume unmounted: "c"
01/14 14:54:59 Updating permissions and modification date of folders on the destination...
01/14 14:55:00 Deleting empty archive folders...
01/14 14:55:00 Summary statistics:

Data copied: 88.64 GB [Please see http://www.bombich.com/log.html for a comment about this figure]
Total data in file set: 105.01 GB
 Regular files copied: 9169
 Largest file encountered: 3.47 GB
 Directories: 4903
 Regular files: 40952
 Symlinks: 14
 Devices: 0
 Special files: 0
 Hard links: 0
 Extended attributes (modified): 8898 (0.29 KB)
 (Unmodified extended attributes are not enumerated)

01/14 14:55:00 Time elapsed: 01:08:08. Data copied: 88.64 GB

================================ Description of the task: CCC will copy the selected items from the folder "yusufpisan" on the volume "Mac" to the folder "jaculusMacPro" on the volume "Sea1TB". If you have backed up to this destination in the past, only items that have been modified since the last backup task will be copied. If you are backing up an installation of Mac OS X, CCC will make every effort to make the destination volume bootable.

• Files and folders in the folder "jaculusMacPro" on "Sea1TB" that are not present on the folder "yusufpisan" on the volume "Mac" will be archived.

• Previously backed up files in the folder "jaculusMacPro" on "Sea1TB" will be updated only if the size or modification date differs.

• Older versions of updated files will be archived.

• CCC will not prune archive content.

1 Posted by Mike Bombich on Jan 14, 2012 @ 11:51 pm

Hi Yusuf:

Do you have any antivirus software running on your Mac? I have seen this same kind of problem with various anti-virus applications:

Antivirus software may interfere with a backup

The main problem I think is that some files get copied to destination with the wrong modified date.

This is what we need to figure out. CCC should be setting the modification date on the destination files appropriately, and I'd like to make sure that there isn't something else touching the file after CCC copies it.

If you have AV software running, let's see if the problem recurs when it is temporarily disabled. If that doesn't explain the problem, I can give you a custom build of CCC that determines the modification date of a file immediately after CCC is finished copying it.

Mike

2 Posted by Yusuf Pisan on Jan 17, 2012 @ 4:54 am

You were right. McAffee (Version 9.0.0) was the culprit for
interacting with the copying process and causing the modified date to
be wrongly set for "today". Once I turned it off, the backup went
smoothly and re-running the backup did not copy any files.

The error was happening much more in large files since McAffee is set
to "scan on write". Probably McAffee was still scanning and buffering
the parts of the file being copied when CCC got done with copying that
file and moved to the next one.

I am not sure what is a good solution, other than turning McAffee off
during updates, so this problem does not repeat itself. Any
suggestions?

Thank you for attending to this issue in a timely manner.

Yusuf

On Sun, Jan 15, 2012 at 3:50 PM, Mike Bombich
tender+d18d0db459f703317e43956338ad1f68a42f64cf0@tenderapp.com
wrote:

PLEASE REPLY ABOVE THIS LINE Any part of your response that is below this line will be discarded

3 Posted by Mike Bombich on Jan 18, 2012 @ 9:40 am

Hi Yusuf:

I don't know of a good solution either. I certainly write the files as quickly as possible :-) AV software really shouldn't affect the modification date of a file, though, I'd consider that to be a bug. I would ask McAfee if they have an explanation for why their software affects the modification date of files on the backup volume.

Mike