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)
-   -   Backup Failed (https://www.shirt-pocket.com/forums/showthread.php?t=6237)

Nick 07-09-2010 01:44 PM

Backup Failed
 
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

Quote:

Originally Posted by dnanian (Post 29467)
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

Quote:

Originally Posted by dnanian (Post 29475)
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

It's happening again...
 
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

Quote:

Originally Posted by dnanian (Post 29484)
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

Quote:

Originally Posted by dnanian (Post 29486)
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

Quote:

Originally Posted by dnanian (Post 29488)
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.


All times are GMT -4. The time now is 05:54 AM.

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