Jon,
OK, I followed your advice and tried a restore, saved the file to the
desktop. When I tried to open it, I got a message that the file was
corrupt and couldn't be opened.
Since I had already run Disk Utility and Disk Warrior repairs on the
backup image, I decided the safest thing at this point would be to
reformat the Drobo and start clean.
So I did. And I ran a backup of my disk (about 380 GB). And it looked
like it would actually complete in less than 24 hours, which seemed
like a good sign (the original backup, on the Drobo that I RMA'ed,
took close to 3 days and nights). And there were absolutely no
(UNDEFINED) errors! Yay!
And it did complete! But then, on the next backup attempt that it
initiated, it failed to mount the disk image, then found the
filesystem dirty, renamed the sparsebundle, and displayed a dialog
that said "Time Machine completed a verification of your backups. To
improve reliability Time Machine must create a new backup for you." I
clicked "Backup Now", but then the console reported that it was unable
to delete the old backup image, and Time Machine exited. At that
point, if I tried a backup, it would keep failing.
I thought perhaps Time Machine failed because it "remembered" the
backups I had done on the old, defective Drobo, and that somehow that
was throwing it off. (Complete speculation on my part.) I started from
scratch again - reformatting the Drobo and reinstalling Backmyfruitup.
And in the Time Machine preference pane, I selected None first (to
clear its record of past backups) before selecting DroboCapsule. I
added DroboCapsule to Spotlight's privacy panel to bake sure it
wouldn't get indexed. I also reduced to a bare minimum other
applications running while Time Machine was working.
And the same thing happened. Backup completed successfully the first
time, but afterwards I go the same series of errors culminating in
Time Machine asking to create a new backup. (This time though, I did
not request a new backup, so I preserved the existing sparsebundle.)
Here is the last part of the log:
5/19/10 2:07:38 PM com.apple.backupd[8527] Copied 84.7 GB of 355.7 GB,
549831 of 2548935 items
5/19/10 3:07:43 PM com.apple.backupd[8527] Copied 96.6 GB of 355.7 GB,
718209 of 2548935 items
5/19/10 3:32:35 PM com.apple.backupd[8527] Error: (-8084) SrcErr:YES
Copying /Users/dave/Library/Mail/SearchHistory.plist to (null)
5/19/10 4:07:44 PM com.apple.backupd[8527] Copied 111.5 GB of 355.7
GB, 773785 of 2548935 items
5/19/10 5:07:45 PM com.apple.backupd[8527] Copied 136.0 GB of 355.7
GB, 776587 of 2548935 items
5/19/10 6:07:46 PM com.apple.backupd[8527] Copied 163.4 GB of 355.7
GB, 779342 of 2548935 items
5/19/10 7:07:47 PM com.apple.backupd[8527] Copied 190.3 GB of 355.7
GB, 783460 of 2548935 items
5/19/10 8:07:50 PM com.apple.backupd[8527] Copied 216.7 GB of 355.7
GB, 788095 of 2548935 items
5/19/10 9:07:51 PM com.apple.backupd[8527] Copied 242.8 GB of 355.7
GB, 791377 of 2548935 items
5/19/10 10:07:51 PM com.apple.backupd[8527] Copied 266.9 GB of 355.7
GB, 793888 of 2548935 items
5/19/10 11:07:52 PM com.apple.backupd[8527] Copied 294.3 GB of 355.7
GB, 793888 of 2548935 items
5/20/10 12:08:01 AM com.apple.backupd[8527] Copied 320.2 GB of 355.7
GB, 796870 of 2548935 items
5/20/10 12:18:47 AM com.apple.backupd[8527] Copied 797369 files (321.1
GB) from volume sputnik.
5/20/10 12:20:56 AM com.apple.backupd[8527] No pre-backup thinning
needed: 3.73 GB requested (including padding), 15.67 TB available
5/20/10 12:20:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:21:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:22:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:23:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:24:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:25:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:26:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:27:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:28:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:29:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:30:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:31:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:32:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:33:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:34:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:35:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:36:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:37:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:38:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:39:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:40:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:41:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:42:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:43:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:44:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:45:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:46:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:47:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:48:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:49:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:50:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:51:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:52:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:53:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:54:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:55:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:56:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:57:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:58:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 12:59:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:00:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:01:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:02:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:03:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:04:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:05:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:06:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:07:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:08:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:09:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:10:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:11:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:12:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:13:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:14:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:15:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:16:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:17:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:18:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:19:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:20:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:21:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:22:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:23:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:24:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:25:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:26:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:27:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:28:56 AM com.apple.backupd[8527] Waiting for index to be
ready (100)
5/20/10 1:29:16 AM com.apple.backupd[8527] Copied 0 bytes of 1021.9
MB, 0 of 455 items
5/20/10 1:44:59 AM com.apple.backupd[8527] Copied 16236 files (828.0
MB) from volume sputnik.
5/20/10 1:47:32 AM com.apple.backupd[8527] Starting post-backup
thinning
5/20/10 1:47:32 AM com.apple.backupd[8527] No post-back up thinning
needed: no expired backups exist
5/20/10 1:47:33 AM com.apple.backupd[8527] Backup completed
successfully.
5/20/10 1:47:41 AM com.apple.backupd[8527] Ejected Time Machine disk
image.
5/20/10 1:47:45 AM com.apple.backupd[8527] Ejected Time Machine
network volume.
5/20/10 2:01:00 AM com.apple.backupd[13203] Starting standard backup
5/20/10 2:01:00 AM com.apple.backupd[13203] Attempting to mount
network destination using URL: afp://;AUTH=No%20User%20Authent@DroboCapsule._afpovertcp._tcp.local/DroboCapsule
5/20/10 2:01:01 AM com.apple.backupd[13203] Mounted network
destination using URL: afp://;AUTH=No%20User%20Authent@DroboCapsule._afpovertcp._tcp.local/DroboCapsule
5/20/10 2:01:01 AM com.apple.backupd[13203] Warning: Destination /
Volumes/DroboCapsule-1 does not support TM Lock Stealing
5/20/10 2:01:01 AM com.apple.backupd[13203] Warning: Destination /
Volumes/DroboCapsule-1 does not support Server Reply Cache
5/20/10 2:01:14 AM com.apple.backupd[13203] QUICKCHECK ONLY;
FILESYSTEM CLEAN
5/20/10 2:01:16 AM com.apple.backupd[13203] Failed to mount disk
image /Volumes/DroboCapsule-1/starts-macbook-
pro-121_64b9e8b7c5ea.sparsebundle (DIHLDiskImageAttach returned: 109)
5/20/10 2:01:16 AM com.apple.backupd[13203] Mount failed... waiting 60
seconds and trying again.
5/20/10 2:01:17 AM com.apple.backupd[13203] Ejected Time Machine
network volume.
5/20/10 2:02:17 AM com.apple.backupd[13203] Attempting to mount
network destination using URL: afp://;AUTH=No%20User%20Authent@DroboCapsule._afpovertcp._tcp.local/DroboCapsule
5/20/10 2:02:17 AM com.apple.backupd[13203] Mounted network
destination using URL: afp://;AUTH=No%20User%20Authent@DroboCapsule._afpovertcp._tcp.local/DroboCapsule
5/20/10 2:02:17 AM com.apple.backupd[13203] Warning: Destination /
Volumes/DroboCapsule-1 does not support TM Lock Stealing
5/20/10 2:02:17 AM com.apple.backupd[13203] Warning: Destination /
Volumes/DroboCapsule-1 does not support Server Reply Cache
5/20/10 2:02:20 AM com.apple.backupd[13203] QUICKCHECK ONLY;
FILESYSTEM DIRTY
5/20/10 2:02:20 AM com.apple.backupd[13203] Runtime corruption
detected on /Volumes/DroboCapsule-1/starts-macbook-
pro-121_64b9e8b7c5ea.sparsebundle (fsck_hfs -q termination status: 3)
5/20/10 2:02:21 AM com.apple.backupd[13203] Network destination
already mounted at: /Volumes/DroboCapsule-1
5/20/10 2:02:21 AM com.apple.backupd[13203] Warning: Destination /
Volumes/DroboCapsule-1 does not support TM Lock Stealing
5/20/10 2:02:21 AM com.apple.backupd[13203] Warning: Destination /
Volumes/DroboCapsule-1 does not support Server Reply Cache
5/20/10 2:02:21 AM com.apple.backupd[13203] Backup verification failed
for image /Volumes/DroboCapsule-1/starts-macbook-
pro-121_64b9e8b7c5ea.sparsebundle!
5/20/10 2:02:21 AM com.apple.backupd[13203] Moved previous backup
image to /Volumes/DroboCapsule-1/starts-macbook-
pro-121_64b9e8b7c5ea_2010-05-20-020221.sparsebundle
5/20/10 6:55:38 AM com.apple.backupd[13203] Recovery backup declined
by user.
5/20/10 6:55:38 AM com.apple.backupd[13203] Backup canceled.
5/20/10 6:55:39 AM com.apple.backupd[13203] Ejected Time Machine
network volume.
At this point, there is a file called starts-macbook-
pro-121_64b9e8b7c5ea_2010-05-20-020221.sparsebundle on DroboCapsule,
but when I attempt to mount it, I get a warning stating that there are
"no mountable filesystems".
One thing I've noticed these past two backup attempts, that I don't
recall from prior backups, is that the volume is referred to as
DroboCapsule-1, not DroboCapsule. But in the Finder it appears simply
as "DroboCapsule".
Any idea what might be going on? I feel like I'm so close!
Best,
Dave
On May 13, 11:33 am, Jon Stevens <
latch...@gmail.com> wrote:
> What I'm saying is that I don't see those errors myself. I'd try a
> restore to ensure that things are backing up and restoring
> successfully. Maybe all of this will go away with the new version of
> netatalk... we shall see.