From: Nick Naym on 14 Apr 2010 21:57 In article 82n5eaF6lnU1(a)mid.individual.net, TaliesinSoft at taliesinsoft(a)me.com wrote on 4/14/10 8:37 PM: > After making my prior posting in this thread I initiated another > SuperDuper! backup of my internal drive, again directing the backup to > a sparse bundle on my Time Capsule. Only this time Time Machine was not > running at the time of launch of SuperDuper!. This time the backup > succeeded. Here is the log.... > > | 06:54:14 PM | Info | SuperDuper!, 2.6.2 (87), path: > /Applications/SuperDuper!.app, Mac OS 10.6.3 build 10D573 (i386) > | 06:54:14 PM | Info | Started on Wed, Apr 14, 2010 at 6:54 PM > | 06:54:14 PM | Info | Source Volume: Cowboy, mount: /, device: > /dev/disk0s2, media: FUJITSU MHW2160BJ G2, interconnect: Internal SATA, > file system: "Journaled HFS+", OS: 10.6.3 (10D573), capacity: 159.70 > GB, used: 87.50 GB, directories: 167494, files: 577812, ejectable: NO, > ACLs: Enabled > | 06:54:14 PM | Info | Target Image: /Volumes/Data/SuperDuper > Backup.sparsebundle, name: SuperDuper Backup > | 06:54:14 PM | Info | Copy Mode : Smart Update > | 06:54:14 PM | Info | Copy Script : Backup - all files.dset > | 06:54:14 PM | Info | Transcript : BuildTranscript.plist > | 06:54:15 PM | Info | PHASE: 1. Prepare to Copy Files > | 06:54:15 PM | Info | ...ACTION: Preparing Cowboy > | 06:54:15 PM | Info | ......COMMAND => Verifying the integrity of > volinfo.database > | 06:54:15 PM | Info | volinfo.database OK > | 06:54:15 PM | Info | ......COMMAND => Enabling permissions on Cowboy > | 06:54:15 PM | Info | Refreshing Disk Arbitration ... > | 06:54:21 PM | Info | ......COMMAND => Verifying that permissions are > enabled for Cowboy > | 06:54:21 PM | Info | Permissions on '/' are enabled. > | 06:54:21 PM | Info | ...ACTION: Mounting SuperDuper Backup > | 06:54:21 PM | Info | ......COMMAND => Preparing SuperDuper Backup > | 06:54:21 PM | Info | ......COMMAND => Setting ownership and access > modes for '/Volumes/Data/SuperDuper Backup.sparsebundle' > | 06:54:21 PM | Info | ......COMMAND => Mounting SuperDuper Backup > | 06:54:23 PM | Info | /dev/disk3 GUID_partition_scheme > > | 06:54:23 PM | Info | /dev/disk3s1 EFI > > | 06:54:23 PM | Info | /dev/disk3s2 Apple_HFS > /Volumes/SuperDuper Backup > | 06:54:23 PM | Info | ......COMMAND => Mounting SuperDuper Backup > | 06:54:23 PM | Info | ...ACTION: Preparing SuperDuper Backup > | 06:54:23 PM | Info | ......COMMAND => Enabling permissions on > SuperDuper Backup > | 06:54:23 PM | Info | Refreshing Disk Arbitration ... > | 06:54:40 PM | Info | ......COMMAND => Verifying that permissions are > enabled for SuperDuper Backup > | 06:54:40 PM | Info | Permissions on '/Volumes/SuperDuper > Backup' are enabled. > | 06:54:40 PM | Info | ......COMMAND => Verifying that SuperDuper > Backup ACL support matches Cowboy > | 06:54:40 PM | Info | ...ACTION: Preserving Spotlight state on > SuperDuper Backup > | 06:54:40 PM | Info | ......COMMAND => Disabling Spotlight search > indexing on SuperDuper Backup > | 06:54:40 PM | Info | PHASE: 2. Copy Files > | 06:54:40 PM | Info | ...ACTION: Copying files from Cowboy to > SuperDuper Backup using Smart Update > | 06:54:40 PM | Info | ......COMMAND => Cloning Cowboy to SuperDuper Backup > | 06:54:41 PM | Info | Copying copy files with delete using > script: /Users/adventures/Library/Application Support/SuperDuper!/Copy > Scripts/Standard Scripts/Backup - all files.dset > | 06:54:41 PM | Info | Loading 27 commands from copy script > /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude > system temporary files.dset > | 06:54:41 PM | Info | Loading 6 commands from copy script > /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude > system cache files.dset > | 06:54:41 PM | Info | Loading 1 commands from copy script > /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude > Norton FileSaver files.dset > | 06:54:41 PM | Info | Loading 2 commands from copy script > /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude > Google Desktop Index files.dset > | 06:54:41 PM | Info | Loading 1 commands from copy script > /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude > iTunes Temporary files.dset > | 06:54:41 PM | Info | Loading 0 commands from copy script > /Users/adventures/Library/Application Support/SuperDuper!/Copy > Scripts/Standard Scripts/Backup - all files.dset > | 06:54:44 PM | Info | / > | 06:54:44 PM | Info | /cores > | 06:54:44 PM | Info | /Network > | 06:54:44 PM | Info | /.Spotlight-V100 > | 06:54:44 PM | Info | Preserving /.Spotlight-V100 > | 06:54:44 PM | Info | /private > | 06:54:47 PM | Info | Ignoring /private/var/folders/D7 > | 06:54:47 PM | Info | Ignoring /private/var/folders/HW > | 06:54:47 PM | Info | Ignoring /private/var/folders/aN > | 06:54:47 PM | Info | Ignoring /private/var/folders/H3 > | 06:54:47 PM | Info | Ignoring /private/var/folders/VX > | 06:54:47 PM | Info | Ignoring /private/var/folders/3X > | 06:54:47 PM | Info | Ignoring /private/var/folders/om > | 06:54:47 PM | Info | Ignoring /private/var/folders/zz > | 06:54:47 PM | Info | Ignoring /private/var/tmp/BonjourPrinters.plist > | 06:54:47 PM | Info | Ignoring /private/var/tmp/Printers-Willie.plist > | 06:54:47 PM | Info | Ignoring /private/var/tmp/Printers-Merle.plist > | 06:54:47 PM | Info | Ignoring /private/var/tmp/Printers-Sidekick.plist > | 06:54:47 PM | Info | Ignoring > /private/var/tmp/com.apple.speech.synthesis.globals > | 06:54:47 PM | Info | Ignoring /private/var/tmp/launchd > | 06:54:50 PM | Info | Ignoring /private/var/vm/sleepimage > | 06:56:17 PM | Info | Ignoring /private/var/vm/swapfile0 > | 06:56:17 PM | Info | Ignoring /private/var/run/vpncontrol.sock > | 06:56:17 PM | Info | Ignoring /private/var/run/usbmuxd > | 06:56:17 PM | Info | Ignoring /private/var/run/syslog > | 06:56:17 PM | Info | Ignoring /private/var/run/printtool > | 06:56:17 PM | Info | Ignoring /private/var/run/portmap.socket > | 06:56:17 PM | Info | Ignoring /private/var/run/mDNSResponder > | 06:56:17 PM | Info | Ignoring /private/var/run/cupsd > | 06:56:17 PM | Info | Ignoring > /private/var/run/com.apple.ActivityMonitor.socket > | 06:56:17 PM | Info | Ignoring /private/var/run/asl_input > | 06:56:17 PM | Info | Ignoring /private/var/run/SCHelper > | 06:56:17 PM | Info | Ignoring /private/var/run/syslog.pid > | 06:56:17 PM | Info | Ignoring /private/var/run/diskarbitrationd.pid > | 06:56:17 PM | Info | Ignoring /private/var/run/configd.pid > | 06:56:17 PM | Info | Ignoring /private/var/run/DirectoryService.pid > | 06:56:17 PM | Info | Ignoring /private/var/run/socketfilterfw.launchd > | 06:56:17 PM | Info | Ignoring /private/var/run/pcscd.pub > | 06:56:17 PM | Info | Ignoring /private/var/run/.DSRunningSP1 > | 06:56:17 PM | Info | Ignoring /private/var/run/appfwd.pid > | 06:56:17 PM | Info | Ignoring > /private/var/run/com.apple.loginwindow.didRunThisBoot > | 06:56:17 PM | Info | Ignoring /private/var/run/automount.initialized > | 06:56:17 PM | Info | Ignoring /private/var/run/mds.pid > | 06:56:17 PM | Info | Ignoring /private/var/run/resolv.conf > | 06:56:17 PM | Info | Ignoring /private/var/run/sntp.log > | 06:56:17 PM | Info | Ignoring /private/var/run/ntpd.pid > | 06:56:17 PM | Info | Ignoring /private/var/run/pppconfd > | 06:56:17 PM | Info | Ignoring /private/var/run/cron.pid > | 06:56:17 PM | Info | Ignoring /private/var/run/utmpx > | 06:56:17 PM | Info | Ignoring /private/var/run/hdiejectd.pid > | 06:56:17 PM | Info | Ignoring /private/var/run/mds > | 06:56:22 PM | Info | Ignoring /private/var/db/BootCache.playlist > | 06:56:22 PM | Info | Ignoring /private/var/db/volinfo.database > | 06:56:38 PM | Info | Ignoring /private/tmp/launchd-116.uU6kI2 > | 06:56:38 PM | Info | Ignoring /private/tmp/launch-ellw5e > | 06:56:38 PM | Info | Ignoring /private/tmp/launch-NxRekv > | 06:56:38 PM | Info | Ignoring /private/tmp/launch-4oGlVd > | 06:56:38 PM | Info | Ignoring /private/tmp/icssuis502 > | 06:56:38 PM | Info | Ignoring /private/tmp/ics165 > | 06:56:38 PM | Info | Ignoring /private/tmp/SDTargetImageDevice > | 06:56:38 PM | Info | Ignoring /private/tmp/target448.indexState > | 06:56:38 PM | Info | /Users > | 07:01:56 PM | Info | /usr > | 07:02:44 PM | Info | /bin > | 07:02:44 PM | Info | /Library > | 07:05:51 PM | Info | /sbin > | 07:05:54 PM | Info | /System > | 07:16:09 PM | Info | /.Trashes > | 07:16:09 PM | Info | Ignoring /.hotfiles.btree > | 07:16:09 PM | Info | /Applications > | 07:24:34 PM | Info | /.fseventsd > | 07:24:34 PM | Info | Preserving /.fseventsd > | 07:24:34 PM | Info | /dev > | 07:24:34 PM | Info | /net > | 07:24:34 PM | Info | /home > | 07:24:34 PM | Info | /Volumes > | 07:24:34 PM | Info | Ignoring /Volumes/Data > | 07:24:34 PM | Info | Ignoring /Volumes/Willie > | 07:24:34 PM | Info | Ignoring /Volumes/SuperDuper Backup > | 07:24:34 PM | Info | Ignoring /Volumes/Merle > | 07:24:34 PM | Info | Ignoring /Volumes/Sidekick > | 07:24:34 PM | Info | Ignoring /Volumes/Cowboy > | 07:24:34 PM | Info | Evaluated 744736 items occupying 86.55 GB > (167495 directories, 567816 files, 9425 symlinks) > | 07:24:34 PM | Info | Copied 1089 items totaling 0.56 GB (184 > directories, 900 files, 5 symlinks) > | 07:24:34 PM | Info | Cloned 83.84 GB of data in 1793 seconds > at an effective transfer rate of 46.76 MB/s > | 07:24:34 PM | Info | ......COMMAND => Resetting Custom Icon bit on > SuperDuper Backup > | 07:24:34 PM | Info | PHASE: 3. After Successful Copy > | 07:24:34 PM | Info | ...ACTION: Making SuperDuper Backup bootable > | 07:24:34 PM | Info | ......COMMAND => Blessing OS X System Folder > | 07:24:36 PM | Info | Successfully blessed Mac OS X folder on > SuperDuper Backup > | 07:24:36 PM | Info | ......COMMAND => Blessing OS 9 System Folder > | 07:24:36 PM | Info | Did not bless Mac OS 9 System Folder on > SuperDuper Backup because it does not exist. > | 07:24:36 PM | Info | ...ACTION: Updating prebinding on SuperDuper Backup > | 07:24:36 PM | Info | ......COMMAND => Updating boot cache on > '/Volumes/SuperDuper Backup' > | 07:25:51 PM | Info | update_dyld_shared_cache[975] current i386 > cache file invalid because > /System/Library/PrivateFrameworks/DesktopServicesPriv.framework/Versions/A/Des > ktopServicesPriv > has changed > | 07:27:05 PM | Info | update_dyld_shared_cache: warning > mismatched install path in > /System/Library/PrivateFrameworks/iLifeMediaBrowser.framework/Versions/A/iLife > MediaBrowser > for /usr/lib/libgcc_s.1.dylib > | 07:27:05 PM | Info | update_dyld_shared_cache: warning > mismatched install path in > /System/Library/CoreServices/RawCamera.bundle/Contents/MacOS/RawCamera > for /usr/lib/libgcc_s.1.dylib > | 07:27:05 PM | Info | update_dyld_shared_cache: warning > mismatched install path in > /System/Library/CoreServices/RawCamera.bundle/Contents/Resources/MetadataLib.d > ylib > for /usr/lib/libgcc_s.1.dylib > | 07:28:50 PM | Info | Successfully updated boot cache on > SuperDuper Backup > | 07:28:50 PM | Info | ...ACTION: Restoring Spotlight state on > SuperDuper Backup > | 07:28:50 PM | Info | ......COMMAND => Restoring Spotlight search > indexing state on SuperDuper Backup > | 07:28:54 PM | Info | /Volumes/SuperDuper Backup: > | 07:28:54 PM | Info | Indexing enabled. > | 07:28:54 PM | Info | Indexing enabled on SuperDuper Backup > | 07:28:54 PM | Info | ...ACTION: Unmounting SuperDuper Backup > | 07:28:54 PM | Info | ......COMMAND => Unmounting > '/Volumes/Data/SuperDuper Backup.sparsebundle' > | 07:28:57 PM | Info | "disk3" unmounted. > | 07:28:57 PM | Info | "disk3" ejected. > | 07:28:57 PM | Info | PHASE: 4. And Finally... > | 07:28:57 PM | Info | ...ACTION: Quitting SuperDuper! > | 07:28:57 PM | Info | ......COMMAND => Quitting SuperDuper! > | 07:28:57 PM | Info | Copy complete. This stuff is all "Geek to me." ;) But, just looking at where the two logs appear to diverge, it sort of seems like the Mac has "lost its way" back to your Time Capsule...or at least to /Volumes/Data/SuperDuper Backup.sparsebundle on your Time Capsule. If so, it comes back to the original question: Why? If it only happens when backing up to Time Capsule, and if then only when SD! and TM are running simultaneously, it seems to suggest that the link to Time Capsule is somehow broken/breaking when the two backups are running together. But that doesn't really tell us much more than we knew before. Since the "bug" that causes Time Capsule's link to break only appears when the two apps come together, whose bug is it? Shirt-Pocket's or Apple's? -- iMac (27", 3.06 GHz Intel Core 2 Duo, 4 GB RAM, 1 TB HDD) � OS X (10.6.3)
From: TaliesinSoft on 14 Apr 2010 22:12 On 2010-04-14 20:57:30 -0500, Nick Naym said: [commenting on the two SuperDuper! logs previously posted in this thread, one where SuperDuper! fails and one where SuperDuper! succeeds, both backups being made to a sparse bundle on a Time Capsule, the difference being that Time Machine was running at the time the failed backup initiated and was not running at the time the successful backup initiated] > This stuff is all "Geek to me." ;) But, just looking at where the two logs > appear to diverge, it sort of seems like the Mac has "lost its way" back to > your Time Capsule...or at least to /Volumes/Data/SuperDuper > Backup.sparsebundle on your Time Capsule. If so, it comes back to the > original question: Why? If it only happens when backing up to Time Capsule, > and if then only when SD! and TM are running simultaneously, it seems to > suggest that the link to Time Capsule is somehow broken/breaking when the > two backups are running together. > > But that doesn't really tell us much more than we knew before. Since the > "bug" that causes Time Capsule's link to break only appears when the two > apps come together, whose bug is it? Shirt-Pocket's or Apple's? As I've mentioned, this problem only started after the release of OS X 10.6.3. The details have been reported to both Shirt-Pocket and Apple, and hopefully between the two of them the issue can be addressed. And, as I've also mentioned, I have a 'workaround" that appears to prevent the backup failure from occurring by insuring that Time Machine is not running at the time SuperDuper! initiates a backup. -- James Leo Ryan --- Austin, Texas --- taliesinsoft(a)me.com
From: Paul Sture on 15 Apr 2010 06:34 In article <82k818Fsa5U1(a)mid.individual.net>, TaliesinSoft <taliesinsoft(a)me.com> wrote: > What I would like is to be able to set the exact time at which the > hourly Time Machine backups take place. For example, 1:00, 2:00, > 3:00..... > > The reason for this is that there is a conflict between SuperDuper! and > Time Machine if Time Machine is running when a scheduled SuperDuper! > backup initiates, the result being a failure of the SuperDuper! backup. > This happens if borth the Time Machine and SuperDuper! backups are to a > Time Capsule. > > So what I want to do is to make sure that Time Machine is not running > at the time a SuperDuper! backup initiates. > > Many thanks in advance for suggestions and such! I'm on Leopard here, if that makes any difference. What I do is set my system to start at 05:59* every morning. Time Machine won't actually do anything until I log in for the first time after a restart, so my first backup of the day is a bit later. However, until I restart the system, Time Machine kicks in every hour at 59 minutes (plus 30 seconds or so) past the hour. If I reboot during the day (I've just done that after applying Security Update 2010-003), then Time Machine will kick in an hour after the reboot time. *Why 05:59 and not on the hour you might ask? There's a clock tower nearby and setting Time Machine off at 59 minutes past the hour through the day means that the hourly rattling of the disks coincides with the chiming of the bells :-) Back-in-Time is a useful utility for seeing when your Time Machine backups have been performed and how much was backed up in each run: <http://www.tri-edre.com/download/download/backintime.html> Tri-Edre also do TechTool Pro and other system level products. -- Paul Sture
From: Paul Sture on 15 Apr 2010 09:19 In article <1Qqxn.1796$Z6.1685(a)edtnps82>, me(a)home.spamsucks.ca (Kir�ly) wrote: > Michelle Steiner <michelle(a)michelle.org> wrote: > > After the initial backups, neither will take more than a half hour, so > > schedule the other one at the half-hour mark. > > I have had both SuperDuper! and Time Machine backups take longer than 30 > minutes. Not often, but it happens. Running 10.5.8 on a G4 with 120GB > of used space on my boot drive. I've only seen it happen once*, but if Time Machine goes into a deep traversal it can take hours to complete. * The one time it did happen, the system log complained about some change in the disk UID (?), and saying it didn't trust things. I think this was after a power cut. -- Paul Sture
From: Paul Sture on 15 Apr 2010 09:21
In article <82mb2mFd4rU1(a)mid.individual.net>, TaliesinSoft <taliesinsoft(a)me.com> wrote: > My gutsy is that the default is based upon when the Mac is > started. I have hard evidence to say that is true (on Leopard, and I'm not using TimeMachineEditor or any similar utilities which do the same thing). -- Paul Sture |