Timemachine & Airport Extreme...just stopped working

[stuntpope]

Hi All,

The setup: various computers throughout the house backing up to a 1.5 TB drive attached to an Airport.

Got back from vacation last night, flipped open my laptop, it happily starts backing up and keeps doing so until around midday today, then it just starts throwing "backup failed", "unable to mount backup location", etc.

Meanwhile, the other computers in the house continue to backup just fine (all of the client computers backup via Wifi)

When I look at the /Volumes on my laptop, i notice this:

drwxrwxrwt@ 7 root admin 238 27 Mar 20:08 .
drwxrwxr-t 36 root admin 1292 19 Mar 20:14 ..
-rw-rw-rw-@ 1 emily admin 6148 23 May 2010 .DS_Store
lrwxr-xr-x 1 root admin 1 27 Mar 19:59 Macintosh HD -> /
drwx------ 20 markjeftovic markjeftovic 636 10 Mar 00:35 easyBackup
drwx------ 20 markjeftovic markjeftovic 636 10 Mar 00:35 easyBackup-1
drwx------ 20 root wheel 636 10 Mar 00:35 easyBackup-2

Which I seem to recall is problematic from last time this flaked out (although last time, a simple reboot fixed things). Now when I reboot, all the "easyBackup" mount points are cleared, but as soon as I try to reconnect to timemachine or start backups, they come back. I do notice one of them, easyBackup-2 seems to "come and go", sometimes it's there, sometimes it's not.

I tried repairing my sparsebundle using my laptop's disk utility, and while it comes up in the left hand pain and I am able to select it and click on "repair", it seems to come back pretty quickly (15 - 20 seconds) with no errors but no details.

Below is a log dump from my console for "backupd". Any ideas here? It seems like this setup flakes out more often than it works.

Starting post-backup thinning
27/03/11 3:58:36 PM /System/Library/CoreServices/backupd[11675] No post-back up thinning needed: no expired backups exist
27/03/11 3:58:36 PM /System/Library/CoreServices/backupd[11675] Backup completed successfully.

So here we are working fine.... and then....somewhere down here, we run into problems (look around 5:01pm)


27/03/11 4:38:34 PM /System/Library/CoreServices/backupd[12280] Backing up to: /Volumes/Backup of Mark Jeftovic’s Computer (2)/Backups.backupdb
27/03/11 4:40:01 PM /System/Library/CoreServices/backupd[12280] No pre-backup thinning needed: 1.71 GB requested (including padding), 1.27 TB available
27/03/11 5:01:06 PM /System/Library/CoreServices/backupd[12280] Error: (-36) SrcErr:NO Copying /Users/markjeftovic/Library/Thunderbird/Profiles/on5tvirg.default/global-messages-db.sqlite to /Volumes/Backup of Mark Jeftovic’s Computer (2)/Backups.backupdb/Mark Jeftovic’s Computer (2)/2011-03-27-163854.inProgress/B327F5FE-E0BD-4F3A-9836-73ECDA6869CF/Macintosh HD/Users/markjeftovic/Library/Thunderbird/Profiles/on5tvirg.default
27/03/11 5:01:06 PM /System/Library/CoreServices/backupd[12280] Stopping backupd to allow ejection of backup destination disk!
27/03/11 5:01:07 PM kernel [ReadUID 0] [Facility com.apple.system.fs] [DevNode /dev/disk2s2] [MountPt /Volumes/Backup of Mark Jeftovic’s Computer (2)] [Path /Volumes/Backup of Mark Jeftovic’s Computer (2)/Backups.backupdb/Mark Jeftovic’s Computer (2)/2011-02-28-080152/Macintosh HD/sbin/SystemStarter] [FSLogMsgID 609157978] [FSLogMsgOrder Last]
27/03/11 5:01:07 PM kernel Node /dev/disk2s2] [MountPt /Volumes/Backup of Mark Jeftovic’s Computer (2)] [Path /Volumes/Backup of Mark Jeftovic’s Computer (2)/Backups.backupdb/Mark Jeftovic’s Computer (2)/2011-03-27-155823/Macintosh HD/Applications/.DS_Store] [FSLogMsgID 89145359] [FSLogMsgOrder Last]
27/03/11 5:01:07 PM /System/Library/CoreServices/backupd[12280] Stopping backupd to allow ejection of backup destination disk!
27/03/11 5:01:08 PM /System/Library/CoreServices/backupd[12280] Error: (-8062) SrcErr:NO Copying /Users/markjeftovic/Library/Thunderbird/Profiles/on5tvirg.default/global-messages-db.sqlite to (null)
27/03/11 5:01:08 PM /System/Library/CoreServices/backupd[12280] Error writing to backup log. NSFileHandleOperationException:*** -[NSConcreteFileHandle writeData:]: Input/output error
27/03/11 5:01:08 PM /System/Library/CoreServices/backupd[12280] Error writing to backup log. NSFileHandleOperationException:*** -[NSConcreteFileHandle writeData:]: Input/output error
27/03/11 5:01:08 PM /System/Library/CoreServices/backupd[12280] Error writing to backup log. NSFileHandleOperationException:*** -[NSConcreteFileHandle writeData:]: Input/output error
27/03/11 5:01:08 PM /System/Library/CoreServices/backupd[12280] Copied 519 files (360.7 MB) from volume Macintosh HD.
27/03/11 5:01:08 PM /System/Library/CoreServices/backupd[12280] Error writing to backup log. NSFileHandleOperationException:*** -[NSConcreteFileHandle writeData:]: Input/output error
27/03/11 5:01:10 PM kernel Facility com.apple.system.fs] [DevNode /dev/disk2s2] [MountPt /Volumes/Backup of Mark Jeftovic’s Computer (2)] [Path /Volumes/Backup of Mark Jeftovic’s Computer (2)/Backups.backupdb/Mark Jeftovic’s Computer (2)/2011-03-27-155823/Macintosh HD/Users/markjeftovic/Library/Application Support/CrossOver/Bottles/CrossOver.conf] [FSLogMsgID 2046169523] [FSLogMsgOrder Last]
27/03/11 5:01:08 PM /System/Library/CoreServices/backupd[12280] Error writing to backup log. NSFileHandleOperationException:*** -[NSConcreteFileHandle writeData:]: Input/output error
27/03/11 5:01:08 PM /System/Library/CoreServices/backupd[12280] Error writing to backup log. NSFileHandleOperationException:*** -[NSConcreteFileHandle writeData:]: Input/output error
27/03/11 5:01:11 PM kernel lumes/Backup of Mark Jeftovic’s Computer (2)/Backups.backupdb/Mark Jeftovic’s Computer (2)/2011-03-27-155823/Macintosh HD/Users/markjeftovic/Library/Application Support/Google/Chrome/Default/Bookmarks] [FSLogMsgID 1845123931] [FSLogMsgOrder Last]
27/03/11 5:01:08 PM /System/Library/CoreServices/backupd[12280] Error writing to backup log. NSFileHandleOperationException:*** -[NSConcreteFileHandle writeData:]: Input/output error
27/03/11 5:01:09 PM /System/Library/CoreServices/backupd[12280] Backup failed with error: 26
27/03/11 5:01:09 PM /System/Library/CoreServices/backupd[12280] [SnapshotUtilities mountPointForVolumeRef] FSGetVolumeInfo returned: -35
27/03/11 5:01:09 PM /System/Library/CoreServices/backupd[12280] Failed to eject volume (null) (FSVolumeRefNum: -132; status: -35; dissenting pid: -1)
27/03/11 5:38:27 PM /System/Library/CoreServices/backupd[13296] Starting standard backup
27/03/11 5:38:28 PM /System/Library/CoreServices/backupd[13296] Mounted network destination using URL: afp://Mark%20Jeftovic@Mark%20Jeftovic's%20AirPort%20Extreme._afpovertcp._tcp.local/easyBackup
27/03/11 5:38:28 PM /System/Library/CoreServices/backupd[13296] Backup destination mounted at path: /Volumes/easyBackup
27/03/11 5:38:28 PM /System/Library/CoreServices/backupd[13296] Failed to mount disk image /Volumes/easyBackup/Mark Jeftovic’s Computer (2)_001b6397345f.sparsebundle (DIHLDiskImageAttach returned: 35)
27/03/11 5:38:28 PM /System/Library/CoreServices/backupd[13296] Waiting 60 seconds and trying again.
27/03/11 5:38:28 PM /System/Library/CoreServices/backupd[13296] Ejected Time Machine network volume.
27/03/11 5:39:29 PM /System/Library/CoreServices/backupd[13296] Mounted network destination using URL: afp://Mark%20Jeftovic@Mark%20Jeftovic's%20AirPort%20Extreme._afpovertcp._tcp.local/easyBackup
27/03/11 5:39:29 PM /System/Library/CoreServices/backupd[13296]

At this point, it's borked, now it just keeps retrying and failed....

27/03/11 8:05:45 PM /System/Library/CoreServices/backupd[269] Ejected Time Machine network volume.
27/03/11 8:06:45 PM /System/Library/CoreServices/backupd[269] Mounted network destination using URL: afp://Mark%20Jeftovic@Mark%20Jeftovic's%20AirPort%20Extreme._afpovertcp._tcp.local/easyBackup
27/03/11 8:06:45 PM /System/Library/CoreServices/backupd[269] Backup destination mounted at path: /Volumes/easyBackup-2
27/03/11 8:06:45 PM /System/Library/CoreServices/backupd[269] Backup destination mounted at path: /Volumes/easyBackup-2
27/03/11 8:06:47 PM /System/Library/CoreServices/backupd[269] Failed to mount disk image /Volumes/easyBackup-2/Mark Jeftovic’s Computer (2)_001b6397345f.sparsebundle (DIHLDiskImageAttach returned: 35)
27/03/11 8:06:47 PM /System/Library/CoreServices/backupd[269] Giving up after 3 retries.
27/03/11 8:06:47 PM /System/Library/CoreServices/backupd[269] Backup destination alias resolved to path: /Volumes/easyBackup-2
27/03/11 8:06:47 PM /System/Library/CoreServices/backupd[269] Failed to mount disk image /Volumes/easyBackup-2/Mark Jeftovic’s Computer (2)_001b6397345f.sparsebundle (DIHLDiskImageAttach returned: 35)
27/03/11 8:06:47 PM /System/Library/CoreServices/backupd[269] Waiting 60 seconds and trying again.
27/03/11 8:06:48 PM /System/Library/CoreServices/backupd[269] Ejected Time Machine network volume.
27/03/11 8:06:52 PM /System/Library/CoreServices/backupd[269] Backup failed with error: 21
27/03/11 8:06:52 PM /System/Library/CoreServices/backupd[269] Backup failed with error: 21
27/03/11 8:07:50 PM /System/Library/CoreServices/backupd[269] Mounted network destination using URL: afp://Mark%20Jeftovic@Mark%20Jeftovic's%20AirPort%20Extreme._afpovertcp._tcp.local/easyBackup
27/03/11 8:07:50 PM /System/Library/CoreServices/backupd[269] Backup destination mounted at path: /Volumes/easyBackup-2
27/03/11 8:07:50 PM /System/Library/CoreServices/backupd[269] Backup destination mounted at path: /Volumes/easyBackup-2
27/03/11 8:07:51 PM /System/Library/CoreServices/backupd[269] Failed to mount disk image /Volumes/easyBackup-2/Mark Jeftovic’s Computer (2)_001b6397345f.sparsebundle (DIHLDiskImageAttach returned: 35)
27/03/11 8:07:51 PM /System/Library/CoreServices/backupd[269] Waiting 60 seconds and trying again.
27/03/11 8:07:52 PM /System/Library/CoreServices/backupd[269] Ejected Time Machine network volume.
27/03/11 8:08:53 PM /System/Library/CoreServices/backupd[269] Mounted network destination using URL: afp://Mark%20Jeftovic@Mark%20Jeftovic's%20AirPort%20Extreme._afpovertcp._tcp.local/easyBackup
27/03/11 8:08:53 PM /System/Library/CoreServices/backupd[269] Backup destination mounted at path: /Volumes/easyBackup-2
27/03/11 8:08:53 PM /System/Library/CoreServices/backupd[269] Backup destination mounted at path: /Volumes/easyBackup-2
27/03/11 8:08:54 PM /System/Library/CoreServices/backupd[269] Failed to mount disk image /Volumes/easyBackup-2/Mark Jeftovic’s Computer (2)_001b6397345f.sparsebundle (DIHLDiskImageAttach returned: 35)
27/03/11 8:08:54 PM /System/Library/CoreServices/backupd[269] Giving up after 3 retries.
27/03/11 8:08:54 PM /System/Library/CoreServices/backupd[269] Giving up after 3 retries.

 

[S.B.G]

Do you have separate partitions for each of the computers backing up to the drive? If so, have you tried erasing the problematic partition and trying the backup again?

Also, what is easybackup? Is that a third-party app of some kind?

 

[stuntpope]

Do you have separate partitions for each of the computers backing up to the drive? If so, have you tried erasing the problematic partition and trying the backup again?

No, no separate backups. Just everything to the same external drive.

Interestingly, I went to bed after I posted this, came down in the morning, sometime overnight the backups started working again, and have been ever since.

It drives me nuts about Timemachine/ Time Capsule, it just seems so flakey half the time.

Also, what is easybackup? Is that a third-party app of some kind?

Haha. No. My main gig is a place called easydns, so I have a habit of calling everything "easy(something)". In this case it's just the name I gave the drive.