|
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.
|