Shirt Pocket Discussions

Shirt Pocket Discussions (https://www.shirt-pocket.com/forums/index.php)
-   General (https://www.shirt-pocket.com/forums/forumdisplay.php?f=6)
-   -   Very slow smart Update..... (https://www.shirt-pocket.com/forums/showthread.php?t=6684)

Vizsla1086 08-08-2012 03:20 PM

Very slow smart Update.....
 
Smart Update is taking hours. Admittedly the disc is large and there are lots of files involved BUT the number of files changed since the last update is very small..........maybe 40?

I can't figure out why the update's so slow. External discs are running on eSATA connections (no port multiplier. Mac Pro has a 4 port PCIe card installed) Usually much. much faster than Firewire 800.

I've included the log for one of the three jobs that are running slowly (I can obviously send logs separately by e-mail if you'd prefer) separately.

Any suggestions? This slow speed is unsettling, to say the least. I would have thought 10 minutes tops to evaluate and copy the 40 files.



| 07:44:20 AM | Info | SuperDuper!, 2.7 (90), path: /Applications/SuperDuper!.app, Mac OS 10.7.4 build 11E53 (i386)
| 07:44:20 AM | Info | Started on Wed, Aug 8, 2012 at 7:44 AM
| 07:44:20 AM | Info | Source Volume: Classical, mount: /Volumes/Classical, device: /dev/disk2s2, media: ST32000641AS, interconnect: Internal SATA, file system: "HFS+", OS: N/A, capacity: 1999.92 GB, used: 1022.55 GB, directories: 3935, files: 57911, ejectable: NO, ACLs: Enabled
| 07:44:20 AM | Info | Target Volume: Backup 2, mount: /Volumes/Backup 2, device: /dev/disk10s1, media: SAMSUNG HD203WI, interconnect: External SCSI Parallel Interface, file system: "Journaled HFS+", OS: N/A, capacity: 2000.40 GB, used: 1020.48 GB, directories: 3921, files: 57745, ejectable: YES, ACLs: Enabled
| 07:44:20 AM | Info | Copy Mode : Smart Update
| 07:44:20 AM | Info | Copy Script : Backup - all files.dset
| 07:44:20 AM | Info | Transcript : BuildTranscript.plist
| 07:44:20 AM | Info | PHASE: 1. Prepare to Copy Files
| 07:44:20 AM | Info | ...ACTION: Preparing Classical
| 07:44:20 AM | Info | ......COMMAND => Verifying the integrity of volinfo.database
| 07:44:20 AM | Info | volinfo.database OK
| 07:44:20 AM | Info | ......COMMAND => Enabling permissions on Classical
| 07:44:21 AM | Info | File system user/group ownership enabled
| 07:44:21 AM | Info | ......COMMAND => Verifying that permissions are enabled for Classical
| 07:44:21 AM | Info | Permissions on '/Volumes/Classical' are enabled.
| 07:44:21 AM | Info | ...ACTION: Preparing Backup 2
| 07:44:21 AM | Info | ......COMMAND => Enabling permissions on Backup 2
| 07:44:21 AM | Info | /dev/disk0s2 on / (hfs, local, journaled)
| 07:44:21 AM | Info | devfs on /dev (devfs, local, nobrowse)
| 07:44:21 AM | Info | /dev/disk1s2 on /Volumes/Misc. Genres (hfs, local)
| 07:44:21 AM | Info | /dev/disk2s2 on /Volumes/Classical (hfs, local)
| 07:44:21 AM | Info | /dev/disk3s2 on /Volumes/Jazz (hfs, local)
| 07:44:21 AM | Info | map -hosts on /net (autofs, nosuid, automounted, nobrowse)
| 07:44:21 AM | Info | map auto_home on /home (autofs, automounted, nobrowse)
| 07:44:21 AM | Info | /dev/disk5s1 on /Volumes/FREE 2 TB (hfs, local, nodev, nosuid, journaled)
| 07:44:21 AM | Info | /dev/disk4s2 on /Volumes/Data (hfs, local, nodev, nosuid, noowners)
| 07:44:21 AM | Info | /dev/disk7s2 on /Volumes/Laura's Carbon Copy (hfs, local, nodev, nosuid, journaled, noowners)
| 07:44:21 AM | Info | /dev/disk6s1 on /Volumes/Carbon Copy (hfs, local, nodev, nosuid, journaled)
| 07:44:21 AM | Info | /dev/disk7s3 on /Volumes/Laura's Super Duper (hfs, local, nodev, nosuid, journaled, noowners)
| 07:44:21 AM | Info | /dev/disk6s2 on /Volumes/Super Duper (hfs, local, nodev, nosuid, journaled)
| 07:44:21 AM | Info | /dev/disk6s3 on /Volumes/Time Machine (hfs, local, nodev, nosuid, journaled)
| 07:44:21 AM | Info | /dev/disk8s2 on /Volumes/Backup 1 (hfs, local, nodev, nosuid, journaled)
| 07:44:21 AM | Info | /dev/disk9s2 on /Volumes/Free 1 TB (hfs, local, nodev, nosuid, journaled, noowners)
| 07:44:21 AM | Info | /dev/disk10s1 on /Volumes/Backup 2 (hfs, local, nodev, nosuid, journaled)
| 07:44:21 AM | Info | /dev/disk11s1 on /Volumes/Backup 3 (hfs, local, nodev, nosuid, journaled)
| 07:44:21 AM | Info | ......COMMAND => Verifying that permissions are enabled for Backup 2
| 07:44:21 AM | Info | Permissions on '/Volumes/Backup 2' are enabled.
| 07:44:21 AM | Info | ......COMMAND => Verifying that Backup 2 ACL support matches Classical
| 07:44:21 AM | Info | ...ACTION: Preserving Spotlight state on Backup 2
| 07:44:21 AM | Info | ......COMMAND => Disabling Spotlight search indexing on Backup 2
| 07:44:21 AM | Info | PHASE: 2. Copy Files
| 07:44:21 AM | Info | ...ACTION: Copying files from Classical to Backup 2 using Smart Update
| 07:44:21 AM | Info | ......COMMAND => Cloning Classical to Backup 2
| 07:44:21 AM | Info | Copying copy files with delete using script: /Users/admin/Library/Application Support/SuperDuper!/Copy Scripts/Standard Scripts/Backup - all files.dset
| 07:44:21 AM | Info | Loading 31 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude system temporary files.dset
| 07:44:21 AM | Info | Loading 6 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude system cache files.dset
| 07:44:21 AM | Info | Loading 1 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude Norton FileSaver files.dset
| 07:44:21 AM | Info | Loading 2 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude Google Desktop Index files.dset
| 07:44:21 AM | Info | Loading 1 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude iTunes Temporary files.dset
| 07:44:21 AM | Info | Loading 2 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude Mozy Cache and Log files.dset
| 07:44:21 AM | Info | Loading 1 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude PGP Whole Disk Encryption files.dset
| 07:44:21 AM | Info | Loading 2 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude Local Revision and Backup files.dset
| 07:44:21 AM | Info | Loading 0 commands from copy script /Users/admin/Library/Application Support/SuperDuper!/Copy Scripts/Standard Scripts/Backup - all files.dset
| 07:44:23 AM | Info | /Volumes/Classical
| 07:44:23 AM | Info | /Volumes/Classical/$RECYCLE.BIN
| 07:44:23 AM | Info | /Volumes/Classical/.Spotlight-V100
| 07:44:23 AM | Info | Preserving /Volumes/Classical/.Spotlight-V100
| 07:44:23 AM | Info | Preserving /Volumes/Classical/.com.apple.timemachine.donotpresent
| 07:44:23 AM | Info | /Volumes/Classical/Classical
| 08:16:35 AM | Info | Disk (null) vanished!
| 11:53:41 AM | Info | /Volumes/Classical/.fseventsd
| 11:53:41 AM | Info | Preserving /Volumes/Classical/.fseventsd
| 11:53:41 AM | Info | /Volumes/Classical/.Trashes
| 11:53:41 AM | Info | Evaluated 61845 items occupying 1022.30 GB (3934 directories, 57911 files, 0 symlinks)
| 11:53:41 AM | Info | Copied 57263 items totaling 921.87 GB (32 directories, 57231 files, 0 symlinks)
| 11:53:41 AM | Info | Cloned 922.05 GB of data in 14960 seconds at an effective transfer rate of 61.63 MB/s
| 11:53:41 AM | Info | ......COMMAND => Resetting Custom Icon bit on Backup 2
| 11:53:41 AM | Info | PHASE: 3. After Successful Copy
| 11:53:41 AM | Info | ...ACTION: Restoring Spotlight state on Backup 2
| 11:53:41 AM | Info | ......COMMAND => Restoring Spotlight search indexing state on Backup 2
| 11:53:42 AM | Info | /Volumes/Backup 2:
| 11:53:42 AM | Info | Indexing enabled.
| 11:53:42 AM | Info | Indexing enabled on Backup 2
| 11:53:42 AM | Info | Copy complete.

dnanian 08-08-2012 03:40 PM

It looks to me like nearly a terabyte of data was copied... 922GB or so!

Vizsla1086 08-09-2012 07:25 AM

Yes, I realize that. Isn't that the problem? Why should "Smart Update" re-copy 1 TB if only 40 files had changed? Maybe it thinks it's "Dumb Update?"

It's happening again. One disc I just "smart Updated yesterday" is now on a schedule for "smart update." It, too, is taking hours but not one single file had been changed sine the past update.

Something's wrong here, no? (or have I got it all wrong. Maybe Smart Update simply copies the entire disc all over again?......That can't be right......)

dnanian 08-09-2012 08:36 AM

It may be that the files have bad dates - dates that aren't able to be replicated on the destination because they're out of normal range for the OSX-level calls (this can happen if the files came from Windows or Windows servers in some cases).

Where did the files come from?

Vizsla1086 08-10-2012 05:30 PM

From ::sigh:: Windows.......
 
It's a bit weird, though. Discs were formatted using MacDrive *while* on the PC, and filled with files. Drives were moved over without issue. I then backed-up up for the first time with Super Duper.

Presumably Super Duper would establish it's own dates, no? We're now on the third iteration.

If not, Is this problem fixable?

Ed

PS. I appreciate the quick responses

Vizsla1086 08-10-2012 05:32 PM

Just adding to my response above....
 
I've just checked the dates on the three affected drives, and they look fine. How would I know if this is the problem?

dnanian 08-10-2012 06:40 PM

Ah, they came from Windows. Thought so. What I'd do is copy to the destination, to make sure it's up to date, then copy the other direction, to reset the dates to 'legal' ones. Subsequent copies should be fine.

Vizsla1086 08-11-2012 07:59 AM

::AAAaaarrrgggghhhhhHHHH:: ::sobbing::
 
Gee......Only 11 hours or so of copying and I'll have fixed the problem.......

::looking for the perfect expanse of wrist to begin slashing::

OK. I appreciate the help. One last question: I've been thinking of getting a couple of new, larger discs to replace the 2 TB ones currently holding the library. If I restore using Super Duper to those (or simply copy from the old discs o the new) will I have solved my date problem?

Thanks,

Ed

dnanian 08-11-2012 08:42 AM

If you copy from the SuperDuper! copy to the new disks, you'll have solved your problem, yes. The point is to make the "adjusted" dates (now in range) the source.

Vizsla1086 08-11-2012 07:58 PM

Many thanks, Dave.......
 
......Unbelievably quick responses.

I'm impressed and appreciative.

RockBottom 08-15-2012 08:16 AM

except you might run into another issue: I tried to backup my boot-drive to a [partitionned] LaCie d2 quadra 3-TB drive, but in the end, none of the partitions are bootable, even though Super Duper didn't detect any anomaly…

dnanian 08-15-2012 08:17 AM

You may have a hardware issue or incompatibility, RockBottom or - if they don't appear in the startup disk preference pane - incorrect partitioning.

Have you contacted me at support?


All times are GMT -4. The time now is 03:47 PM.

Powered by vBulletin® Version 3.8.9
Copyright ©2000 - 2024, vBulletin Solutions, Inc.