PDA

View Full Version : Backup Failed


Nick
07-09-2010, 01:44 PM
What gives?

———————————————

| 01:21:00 PM | Info | SuperDuper!, 2.6.2 (87), path: /Applications/SuperDuper!.app, Mac OS 10.6.4 build 10F569 (i386)
| 01:21:00 PM | Info | Started on Fri, Jul 9, 2010 at 1:21 PM
| 01:21:00 PM | Info | Source Volume: Nick's HD, mount: /, device: /dev/disk0s2, media: ST31000528ASQ, interconnect: Internal SATA, file system: "Journaled HFS+", OS: 10.6.4 (10F569), capacity: 999.86 GB, used: 594.95 GB, directories: 141356, files: 2510412, ejectable: NO, ACLs: Enabled
| 01:21:00 PM | Info | Target Volume: LaCie d2 Quadra, mount: /Volumes/LaCie d2 Quadra, device: /dev/disk1s2, media: LaCie d2 quadra, interconnect: External FireWire, file system: "Journaled HFS+", OS: 10.6.4 (10F569), capacity: 749.68 GB, used: 570.68 GB, directories: 141228, files: 2509725, ejectable: YES, ACLs: Enabled
| 01:21:00 PM | Info | Copy Mode : Smart Update
| 01:21:00 PM | Info | Copy Script : Backup - all files.dset
| 01:21:00 PM | Info | Transcript : BuildTranscript.plist
| 01:21:00 PM | Info | PHASE: 1. Prepare to Copy Files
| 01:21:00 PM | Info | ...ACTION: Preparing Nick's HD
| 01:21:00 PM | Info | ......COMMAND => Verifying the integrity of volinfo.database
| 01:21:00 PM | Info | volinfo.database OK
| 01:21:00 PM | Info | ......COMMAND => Enabling permissions on Nick's HD
| 01:21:00 PM | Info | Refreshing Disk Arbitration ...
| 01:21:01 PM | Info | ......COMMAND => Verifying that permissions are enabled for Nick's HD
| 01:21:01 PM | Info | Permissions on '/' are enabled.
| 01:21:01 PM | Info | ...ACTION: Preparing LaCie d2 Quadra
| 01:21:01 PM | Info | ......COMMAND => Enabling permissions on LaCie d2 Quadra
| 01:21:01 PM | Info | Refreshing Disk Arbitration ...
| 01:21:01 PM | Info | ......COMMAND => Verifying that permissions are enabled for LaCie d2 Quadra
| 01:21:01 PM | Info | Permissions on '/Volumes/LaCie d2 Quadra' are enabled.
| 01:21:01 PM | Info | ......COMMAND => Verifying that LaCie d2 Quadra ACL support matches Nick's HD
| 01:21:01 PM | Info | ...ACTION: Preserving Spotlight state on LaCie d2 Quadra
| 01:21:01 PM | Info | ......COMMAND => Disabling Spotlight search indexing on LaCie d2 Quadra
| 01:21:01 PM | Info | PHASE: 2. Copy Files
| 01:21:01 PM | Info | ...ACTION: Copying files from Nick's HD to LaCie d2 Quadra using Smart Update
| 01:21:01 PM | Info | ......COMMAND => Cloning Nick's HD to LaCie d2 Quadra
| 01:21:07 PM | Info | Copying copy files with delete using script: /Users/Nick/Library/Application Support/SuperDuper!/Copy Scripts/Standard Scripts/Backup - all files.dset
| 01:21:07 PM | Info | Loading 27 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude system temporary files.dset
| 01:21:07 PM | Info | Loading 6 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude system cache files.dset
| 01:21:07 PM | Info | Loading 1 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude Norton FileSaver files.dset
| 01:21:07 PM | Info | Loading 2 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude Google Desktop Index files.dset
| 01:21:07 PM | Info | Loading 1 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude iTunes Temporary files.dset
| 01:21:07 PM | Info | Loading 0 commands from copy script /Users/Nick/Library/Application Support/SuperDuper!/Copy Scripts/Standard Scripts/Backup - all files.dset
| 01:21:07 PM | Info | /
| 01:21:07 PM | Info | /.vol
| 01:21:07 PM | Info | Ignoring /.vol
| 01:21:07 PM | Info | /cores
| 01:21:07 PM | Info | Unable to copy extended attributes from directory /cores to directory /Volumes/LaCie d2 Quadra/cores. Invalid argument
| 01:21:07 PM | Error | SDCopy: Failed to copy extended attributes to directory /Volumes/LaCie d2 Quadra/cores\n: Invalid argument
| 01:21:07 PM | Error | ****FAILED****: result=5632 errno=22 (Unknown error: 0)

dnanian
07-09-2010, 02:01 PM
Restart your Mac, power cycle your drives, and try again.

Nick
07-09-2010, 07:36 PM
Restart your Mac, power cycle your drives, and try again.

As usual, you responded virtually immediately, and your response was the solution.

1. How do you do it? :confused: ;)

2. What was the problem (and what about the log indicated the problem)?

dnanian
07-10-2010, 12:55 PM
1. Experience, mostly.

2. The 5632 error code is something I've seen from the system that basically says "restart, something internal is messed up"...

Nick
07-10-2010, 03:06 PM
1. Experience, mostly.

2. The 5632 error code is something I've seen from the system that basically says "restart, something internal is messed up"...

Strictly out of curiosity: Any guess as to what would be included in the "Internal Things That Are Messed Up" list?

dnanian
07-10-2010, 05:40 PM
Not really: I didn't write OSX itself and can't really debug it as such.

Nick
07-13-2010, 10:04 PM
I’m running into a similar problem with the same external drive. I attempted the same “solution,” but it didn’t help: When I tried to do a backup manually, it failed even sooner.

I wanted to post the two logs that SD! created (one for each attempt), but for some reason, the site wasn’t responding earlier today. (Other sites were available, so I can only assume there was a connection problem specific to shirt-pocket.com.)

Here are the two logs:


First (scheduled) backup attempt:
————————
| 01:10:42 PM | Info | SuperDuper!, 2.6.2 (87), path: /Applications/SuperDuper!.app, Mac OS 10.6.4 build 10F569 (i386)
| 01:10:42 PM | Info | Started on Tue, Jul 13, 2010 at 1:10 PM
| 01:10:42 PM | Info | Source Volume: Nick's HD, mount: /, device: /dev/disk0s2, media: ST31000528ASQ, interconnect: Internal SATA, file system: "Journaled HFS+", OS: 10.6.4 (10F569), capacity: 999.86 GB, used: 573.69 GB, directories: 135399, files: 2497090, ejectable: NO, ACLs: Enabled
| 01:10:42 PM | Info | Target Volume: LaCie d2 Quadra, mount: /Volumes/LaCie d2 Quadra, device: /dev/disk3s2, media: LaCie d2 quadra, interconnect: External FireWire, file system: "Journaled HFS+", OS: 10.6.4 (10F569), capacity: 749.68 GB, used: 569.83 GB, directories: 135208, files: 2494408, ejectable: YES, ACLs: Enabled
| 01:10:42 PM | Info | Copy Mode : Smart Update
| 01:10:42 PM | Info | Copy Script : Backup - all files.dset
| 01:10:42 PM | Info | Transcript : BuildTranscript.plist
| 01:10:42 PM | Info | PHASE: 1. Prepare to Copy Files
| 01:10:42 PM | Info | ...ACTION: Preparing Nick's HD
| 01:10:42 PM | Info | ......COMMAND => Verifying the integrity of volinfo.database
| 01:10:42 PM | Info | volinfo.database OK
| 01:10:42 PM | Info | ......COMMAND => Enabling permissions on Nick's HD
| 01:10:42 PM | Info | Refreshing Disk Arbitration ...
| 01:10:42 PM | Info | ......COMMAND => Verifying that permissions are enabled for Nick's HD
| 01:10:42 PM | Info | Permissions on '/' are enabled.
| 01:10:42 PM | Info | ...ACTION: Preparing LaCie d2 Quadra
| 01:10:42 PM | Info | ......COMMAND => Enabling permissions on LaCie d2 Quadra
| 01:10:42 PM | Info | Refreshing Disk Arbitration ...
| 01:10:43 PM | Info | ......COMMAND => Verifying that permissions are enabled for LaCie d2 Quadra
| 01:10:43 PM | Info | Permissions on '/Volumes/LaCie d2 Quadra' are enabled.
| 01:10:43 PM | Info | ......COMMAND => Verifying that LaCie d2 Quadra ACL support matches Nick's HD
| 01:10:43 PM | Info | ...ACTION: Preserving Spotlight state on LaCie d2 Quadra
| 01:10:43 PM | Info | ......COMMAND => Disabling Spotlight search indexing on LaCie d2 Quadra
| 01:10:43 PM | Info | PHASE: 2. Copy Files
| 01:10:43 PM | Info | ...ACTION: Copying files from Nick's HD to LaCie d2 Quadra using Smart Update
| 01:10:43 PM | Info | ......COMMAND => Cloning Nick's HD to LaCie d2 Quadra
| 01:10:44 PM | Info | Copying copy files with delete using script: /Users/Nick/Library/Application Support/SuperDuper!/Copy Scripts/Standard Scripts/Backup - all files.dset
| 01:10:44 PM | Info | Loading 27 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude system temporary files.dset
| 01:10:44 PM | Info | Loading 6 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude system cache files.dset
| 01:10:44 PM | Info | Loading 1 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude Norton FileSaver files.dset
| 01:10:44 PM | Info | Loading 2 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude Google Desktop Index files.dset
| 01:10:44 PM | Info | Loading 1 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude iTunes Temporary files.dset
| 01:10:44 PM | Info | Loading 0 commands from copy script /Users/Nick/Library/Application Support/SuperDuper!/Copy Scripts/Standard Scripts/Backup - all files.dset
| 01:10:47 PM | Info | /
| 01:10:47 PM | Info | /.vol
| 01:10:47 PM | Info | Ignoring /.vol
| 01:10:47 PM | Info | /cores
| 01:10:47 PM | Info | /Network
| 01:10:47 PM | Info | /private
| 01:10:47 PM | Info | Ignoring /private/var/folders/Ei
| 01:10:47 PM | Info | Ignoring /private/var/folders/zz
| 01:10:47 PM | Info | Ignoring /private/var/folders/Gd
| 01:10:47 PM | Info | Ignoring /private/var/folders/7c
| 01:10:53 PM | Info | Ignoring /private/var/tmp/.BlankFile
| 01:10:53 PM | Info | Ignoring /private/var/tmp/BonjourPrinters.plist
| 01:10:53 PM | Info | Ignoring /private/var/tmp/Printers-CompUSA Western Digital.plist
| 01:10:53 PM | Info | Ignoring /private/var/tmp/Printers-TechTool Pro 4 DVD.plist
| 01:10:53 PM | Info | Ignoring /private/var/tmp/Printers-LaCie.plist
| 01:10:53 PM | Info | Ignoring /private/var/tmp/Printers-Seagate Barracuda.plist
| 01:10:53 PM | Info | Ignoring /private/var/tmp/Printers-Nick's HD Clone.plist
| 01:10:53 PM | Info | Ignoring /private/var/tmp/Printers-LaCie 1TB.plist
| 01:10:53 PM | Info | Ignoring /private/var/tmp/Printers-LaCie d2 Quadra.plist
| 01:10:53 PM | Info | Ignoring /private/var/tmp/com.apple.speech.synthesis.globals
| 01:10:53 PM | Info | Ignoring /private/var/tmp/launchd
| 01:10:53 PM | Info | Ignoring /private/var/vm/swapfile0
| 01:10:53 PM | Info | Ignoring /private/var/vm/swapfile2
| 01:10:53 PM | Info | Ignoring /private/var/vm/swapfile1
| 01:10:53 PM | Info | Ignoring /private/var/run/vpncontrol.sock
| 01:10:53 PM | Info | Ignoring /private/var/run/usbmuxd
| 01:10:53 PM | Info | Ignoring /private/var/run/syslog.pid
| 01:10:53 PM | Info | Ignoring /private/var/run/syslog
| 01:10:53 PM | Info | Ignoring /private/var/run/printtool
| 01:10:53 PM | Info | Ignoring /private/var/run/portmap.socket
| 01:10:53 PM | Info | Ignoring /private/var/run/mDNSResponder
| 01:10:53 PM | Info | Ignoring /private/var/run/diskarbitrationd.pid
| 01:10:53 PM | Info | Ignoring /private/var/run/cupsd
| 01:10:53 PM | Info | Ignoring /private/var/run/configd.pid
| 01:10:53 PM | Info | Ignoring /private/var/run/com.barebones.textwrangler.socket
| 01:10:53 PM | Info | Ignoring /private/var/run/com.barebones.bbedit.socket
| 01:10:53 PM | Info | Ignoring /private/var/run/com.apple.ActivityMonitor.socket
| 01:10:53 PM | Info | Ignoring /private/var/run/asl_input
| 01:10:53 PM | Info | Ignoring /private/var/run/SCHelper
| 01:10:53 PM | Info | Ignoring /private/var/run/DirectoryService.pid
| 01:10:53 PM | Info | Ignoring /private/var/run/.DSRunningSP1
| 01:10:53 PM | Info | Ignoring /private/var/run/pcscd.pub
| 01:10:53 PM | Info | Ignoring /private/var/run/macaronid.pid
| 01:10:53 PM | Info | Ignoring /private/var/run/pppconfd
| 01:10:53 PM | Info | Ignoring /private/var/run/cron.pid
| 01:10:53 PM | Info | Ignoring /private/var/run/automount.initialized
| 01:10:53 PM | Info | Ignoring /private/var/run/mds.pid
| 01:10:53 PM | Info | Ignoring /private/var/run/com.apple.loginwindow.didRunThisBoot
| 01:10:53 PM | Info | Ignoring /private/var/run/utmpx
| 01:10:53 PM | Info | Ignoring /private/var/run/mds
| 01:10:53 PM | Info | Ignoring /private/var/run/sntp.log
| 01:10:53 PM | Info | Ignoring /private/var/run/ntpd.pid
| 01:10:53 PM | Info | Ignoring /private/var/run/resolv.conf
| 01:10:53 PM | Info | Ignoring /private/var/run/.autoBackup
| 01:11:09 PM | Info | Ignoring /private/var/db/BootCache.playlist
| 01:11:09 PM | Info | Ignoring /private/var/db/volinfo.database
| 01:11:09 PM | Info | Ignoring /private/tmp/launchd-108.wasZSz
| 01:11:09 PM | Info | Ignoring /private/tmp/launch-Looaae
| 01:11:09 PM | Info | Ignoring /private/tmp/launch-C9PNFc
| 01:11:09 PM | Info | Ignoring /private/tmp/launch-3154dK
| 01:11:09 PM | Info | Ignoring /private/tmp/icssuis501
| 01:11:09 PM | Info | Ignoring /private/tmp/ics143
| 01:11:09 PM | Info | Ignoring /private/tmp/target727.indexState
| 01:11:09 PM | Info | /.Spotlight-V100
| 01:11:09 PM | Info | Preserving /.Spotlight-V100
| 01:11:09 PM | Info | /Developer
| 01:11:30 PM | Info | /.TemporaryItems
| 01:11:30 PM | Info | /Copies of Backups
| 01:12:16 PM | Info | /Users
| 01:13:07 PM | Info | Error copying /Users/Nick/Flash Drives/4.0 GB SanDisk Cruzer (BH0709JTKB)/JT's HD (Basic Backup)/Nick Naym's HD.122207/untitled folder-B:U HD/Poetry/The Interactive Raven/::www.teachersfirst.co.pdf to /Volumes/LaCie d2 Quadra/Users/Nick/Flash Drives/4.0 GB SanDisk Cruzer (BH0709JTKB)/JT's HD (Basic Backup)/Nick Naym's HD.122207/untitled folder-B:U HD/Poetry/The Interactive Raven/::www.teachersfirst.co.pdf of type 8 due to error 1
| 01:13:07 PM | Error | SDCopy: Error copying /Users/Nick/Flash Drives/4.0 GB SanDisk Cruzer (BH0709JTKB)/JT's HD (Basic Backup)/Nick Naym's HD.122207/untitled folder-B:U HD/Poetry/The Interactive Raven/::www.teachersfirst.co.pdf to /Volumes/LaCie d2 Quadra/Users/Nick/Flash Drives/4.0 GB SanDisk Cruzer (BH0709JTKB)/JT's HD (Basic Backup)/Nick Naym's HD.122207/untitled folder-B:U HD/Poetry/The Interactive Raven/::www.teachersfirst.co.pdf of type 8 due to error 1\n: Resource busy

————————

(Continued in next post)

Nick
07-13-2010, 10:07 PM
(Continued from previous post...)

Second (manual) backup attempt:
————————
| 02:20:12 PM | Info | SuperDuper!, 2.6.2 (87), path: /Applications/SuperDuper!.app, Mac OS 10.6.4 build 10F569 (i386)
| 02:20:12 PM | Info | Started on Tue, Jul 13, 2010 at 2:20 PM
| 02:20:12 PM | Info | Source Volume: Nick's HD, mount: /, device: /dev/disk0s2, media: ST31000528ASQ, interconnect: Internal SATA, file system: "Journaled HFS+", OS: 10.6.4 (10F569), capacity: 999.86 GB, used: 570.71 GB, directories: 135398, files: 2497047, ejectable: NO, ACLs: Enabled
| 02:20:12 PM | Info | Target Volume: LaCie d2 Quadra, mount: /Volumes/LaCie d2 Quadra, device: /dev/disk3s2, media: LaCie d2 quadra, interconnect: External FireWire, file system: "Journaled HFS+", OS: 10.6.4 (10F569), capacity: 749.68 GB, used: 569.84 GB, directories: 135208, files: 2494210, ejectable: YES, ACLs: Enabled
| 02:20:12 PM | Info | Copy Mode : Smart Update
| 02:20:12 PM | Info | Copy Script : Backup - all files.dset
| 02:20:12 PM | Info | Transcript : BuildTranscript.plist
| 02:20:13 PM | Info | PHASE: 1. Prepare to Copy Files
| 02:20:13 PM | Info | ...ACTION: Preparing Nick's HD
| 02:20:13 PM | Info | ......COMMAND => Verifying the integrity of volinfo.database
| 02:20:23 PM | Info | volinfo.database OK
| 02:20:23 PM | Info | ......COMMAND => Enabling permissions on Nick's HD
| 02:20:23 PM | Info | Refreshing Disk Arbitration ...
| 02:20:23 PM | Info | ......COMMAND => Verifying that permissions are enabled for Nick's HD
| 02:20:23 PM | Info | Permissions on '/' are enabled.
| 02:20:23 PM | Info | ...ACTION: Preparing LaCie d2 Quadra
| 02:20:23 PM | Info | ......COMMAND => Enabling permissions on LaCie d2 Quadra
| 02:20:23 PM | Info | Refreshing Disk Arbitration ...
| 02:20:24 PM | Error | GetMasterBlock: Error 16 from read; amount=-1, wanted=512
————————


Any ideas?

dnanian
07-13-2010, 10:34 PM
It's a problem again with a drive that's attached, Nick, either locally or networked. Definitely something is not responding properly...

Nick
07-13-2010, 10:44 PM
It's a problem again with a drive that's attached, Nick, either locally or networked. Definitely something is not responding properly...

It's the same drive as before...

I have 3 FW externals, daisy-chained to the Mac: a 2TB, 1 TB, and 750 GB (in that order), all LaCie Quadras. The 750 is at the end of the chain; it's the oldest (circa Sept. 2008), the other 2 having recently (a few months ago) been acquired. The 2 TB is for TM backups; the 1 TB and 750 GB are for SD! backups.

What would you suggest I do?

dnanian
07-13-2010, 11:13 PM
Try switching one to USB to see if that helps?

Nick
07-13-2010, 11:29 PM
Try switching one to USB to see if that helps?

:confused: I don't understand.

dnanian
07-13-2010, 11:29 PM
Rather than connecting all three drives to FireWire, switch one to USB instead.

Nick
07-13-2010, 11:35 PM
Rather than connecting all three drives to FireWire, switch one to USB instead.

The 750?

But let's say I do...what am I trying to determine?

PS: FWIW, I tried running DU (as well as TTPro and DW), but they each failed to run their tests, as they each couldn't unmount the volume.

dnanian
07-14-2010, 07:46 AM
You're trying to determine whether the problem is with the interface (USB vs FW) or the drive.

Nick
07-14-2010, 08:21 AM
You're trying to determine whether the problem is with the interface (USB vs FW) or the drive.

It's the drive: Sometimes it mounts, other times it doesn't; sometimes I can unmount and/or eject it, other times I can't. And if and when I can get to the actual volume, some of the folders are now empty; and if I attempt to drag & drop folders from my internal HD or my other SD! clone to replace them, I get messages telling me it can't be done, because they're in use! (Same results for either interface.)

It's trashed, I guess.

dnanian
07-14-2010, 08:29 AM
Try moving it to USB and see if it starts behaving better, Nick.

Nick
07-14-2010, 08:30 AM
Try moving it to USB and see if it starts behaving better, Nick.

Same results for either interface.

Nick
07-16-2010, 10:21 AM
...

It's trashed, I guess.

Well, I decided I needed to reformat/reinitialize the drive. So, I opened DU and attempted to do so. However, my repeated attempts failed: After about 10 seconds of churning after I clicked the “partition” button, DU gave up and issued the error message: “Partition failed with the error: Could not unmount disk.”

I then tried to reformat/reinitialize via the USB interface as well, and that too was unsuccessful, but the error message was different: “Partition failed with the error: POSIX reports: The operation couldn’t be completed. Cannot allocate memory.”

dnanian
07-16-2010, 10:22 AM
Have you restarted your Mac?

Nick
07-16-2010, 10:34 AM
Have you restarted your Mac?

Numerous times. This last time, the volume doesn't even appear—only the drive shows up, and it only shows up in DU, not in the Finder. (This has happened periodically before over these past few days, but now it happens all the time: The volume doesn't appear.)

dnanian
07-16-2010, 10:39 AM
You may be having some more serious problems with your OS install. Getting memory allocation problems with stuff like this shouldn't happen. I'd reinstall Snow Leopard (equivalent of the old archive-and-install), and turn off any antivirus and similar apps you might have running.

Nick
07-16-2010, 10:47 AM
You may be having some more serious problems with your OS install. Getting memory allocation problems with stuff like this shouldn't happen. I'd reinstall Snow Leopard (equivalent of the old archive-and-install), and turn off any antivirus and similar apps you might have running.

Reinstall (the machine is only a few months old)?? Oy vey! :eek: :(

dnanian
07-16-2010, 10:57 AM
That's certainly what I'd try. It's pretty painless, and you're getting terribly weird things here.

Nick
07-16-2010, 11:03 AM
That's certainly what I'd try. It's pretty painless, and you're getting terribly weird things here.

Doing a full OS reinstall is painless? The last time I did that (previous machine, OS 10.5) it was anything but. Is SL inherently easier to reinstall?

dnanian
07-16-2010, 11:10 AM
You just reinstall. It puts a fresh os under your existing applications and data. It shouldn't be difficult.

sjk
07-16-2010, 05:43 PM
It shouldn't be difficult.
I agree that's generally true. When difficulties do occur with reinstalls they can be useful symptoms of specific problems that deserve attention to rectify so they're avoided in the future (if possible). Problematic reinstalls/updates can be a sign to consider managing a system differently.