I have a Time Capsule and use it for wirelessly backing up my mac mini. Things will work great for like 2 weeks, and then the .sparsebundle just goes bad! I thought that these types of problems had been addressed in OS X v10.5.3, but I guess they still persist. Here is the latest incident that happened with an hourly backup that should have consisted of only 200kb or so:
When I attempt to examine the .sparsebundle file using hdiutil it gives a "Resource busy" error #35. In addition, Disk Warrior is having no luck accessing the .sparsebundle. It looks like it is completely hosed. I tried the trick of moving out the highest numbered "band" file, but no luck with that. I wonder if the network connection to the Time Capsule was lost at a critical moment in time, such as when directory information for the .sparsebundle was being updated.
Code:
4/4/09 1:22:43 PM /System/Library/CoreServices/backupd[14036] Starting standard backup
4/4/09 1:22:53 PM /System/Library/CoreServices/backupd[14036] Mounted network destination using URL: afp://jtm@jasper.local/Data
4/4/09 1:22:53 PM /System/Library/CoreServices/backupd[14036] Backup destination mounted at path: /Volumes/Data
4/4/09 1:23:06 PM /System/Library/CoreServices/backupd[14036] Disk image /Volumes/Data/ivan_0023df9d6656.sparsebundle mounted at: /Volumes/Backup of ivan
4/4/09 1:23:08 PM /System/Library/CoreServices/backupd[14036] Backing up to: /Volumes/Backup of ivan/Backups.backupdb
4/4/09 1:27:02 PM /System/Library/CoreServices/backupd[14036] No pre-backup thinning needed: 242.2 MB requested (including padding), 310.30 GB available
4/4/09 1:43:16 PM /System/Library/CoreServices/backupd[14036] Copied 540 files (221 KB) from volume Ivan.
4/4/09 1:43:17 PM /System/Library/CoreServices/backupd[14036] No pre-backup thinning needed: 242.2 MB requested (including padding), 310.30 GB available
4/4/09 1:44:43 PM /System/Library/CoreServices/backupd[14036] Copied 254 files (217 KB) from volume Ivan.
4/4/09 1:45:53 PM /System/Library/CoreServices/backupd[14036] Starting post-backup thinning
4/4/09 1:49:30 PM kernel ASP_TCP Disconnect: triggering reconnect by bumping reconnTrigger from cuFacility com.apple.system.fs] [DevNode /dev/disk2s2] [MountPt /Volumes/Backup of ivan] [FSLogMsgID 327830650] [FSLogMsgOrder Last]
4/4/09 1:49:30 PM kernel Node /dev/disk2s2] [MountPt /Volumes/Backup of ivan] [FSLogMsgID 1450635452] [FSLogMsgOrder Last]
4/4/09 1:49:30 PM /System/Library/CoreServices/backupd[14036] Error -47 deleting backup: /Volumes/Backup of ivan/Backups.backupdb/ivan/2009-04-03-132634
4/4/09 1:49:30 PM /System/Library/CoreServices/backupd[14036] Deleted backup /Volumes/Backup of ivan/Backups.backupdb/ivan/2009-04-03-132634: 0.0 KB now available
4/4/09 1:49:30 PM /System/Library/CoreServices/backupd[14036] Error writing to backup log. NSFileHandleOperationException:*** -[NSConcreteFileHandle writeData:]: Input/output error
4/4/09 1:49:30 PM /System/Library/CoreServices/backupd[14036] Error -36 deleting backup: /Volumes/Backup of ivan/Backups.backupdb/ivan/2009-04-03-122545
4/4/09 1:49:30 PM /System/Library/CoreServices/backupd[14036] Post-back up thinning complete: 1 expired backups removed
4/4/09 1:49:30 PM /System/Library/CoreServices/backupd[14036] Warning: backup disk now has only 0.0 KB free without removing old backups
4/4/09 1:49:31 PM /System/Library/CoreServices/backupd[14036] Stopping backupd to allow ejection of backup destination disk!
4/4/09 1:49:31 PM fseventsd[46] disk logger: gzclose() failed (-1/22) removing all old log files in /Volumes/Backup of ivan/.fseventsd
4/4/09 1:49:31 PM fseventsd[46] failed to unlink old log file /Volumes/Backup of ivan/.fseventsd/00000000a64fb21f (Invalid argument)
[i]snip[/i]
4/4/09 1:49:31 PM mds[39] (/)(Error) IndexSDB in add_dirty_chunk:/Volumes/Backup of ivan/.Spotlight-V100/Store-V1/Stores/FF0A8CC4-DC00-4A6E-BEC1-570B33C594E6/store.db : ERR: Can't write DST header (Input/output error)
4/4/09 1:49:31 PM fseventsd[46] failed to unlink old log file /Volumes/Backup of ivan/.fseventsd/00000000a672c79b (Invalid argument)
4/4/09 1:49:31 PM fseventsd[46] failed to unlink old log file /Volumes/Backup of ivan/.fseventsd/00000000a6730674 (Invalid argument)
[i]snip[/i]
4/4/09 1:49:31 PM mds[39] (/Volumes/Backup of ivan/.Spotlight-V100/Store-V1/Stores/FF0A8CC4-DC00-4A6E-BEC1-570B33C594E6)(Error) IndexCI in ci_ftruncate:ftruncate(53 /Volumes/Backup of ivan/.Spotlight-V100/Store-V1/Stores/FF0A8CC4-DC00-4A6E-BEC1-570B33C594E6/live.1.indexPostings, 16777216) error:22
4/4/09 1:49:31 PM fseventsd[46] failed to unlink old log file /Volumes/Backup of ivan/.fseventsd/00000000a67875a3 (Invalid argument)
4/4/09 1:49:31 PM mds[39] (/Volumes/Backup of ivan/.Spotlight-V100/Store-V1/Stores/27FF551F-62A1-49C8-9FEB-6DB1B2D8A3E8)(Error) IndexCI in ci_ftruncate:ftruncate(88 /Volumes/Backup of ivan/.Spotlight-V100/Store-V1/Stores/27FF551F-62A1-49C8-9FEB-6DB1B2D8A3E8/live.1.indexPostings, 4096) error:22
4/4/09 1:49:31 PM mds[39] (/Volumes/Backup of ivan/.Spotlight-V100/Store-V1/Stores/FF0A8CC4-DC00-4A6E-BEC1-570B33C594E6)(Error) IndexCI in initPayload:ftruncate err: 22
4/4/09 1:49:31 PM fseventsd[46] failed to unlink old log file /Volumes/Backup of ivan/.fseventsd/00000000a678b627 (Invalid argument)
4/4/09 1:49:31 PM mds[39] (/Volumes/Backup of ivan/.Spotlight-V100/Store-V1/Stores/27FF551F-62A1-49C8-9FEB-6DB1B2D8A3E8)(Error) IndexCI in initPayload:ftruncate err: 22
4/4/09 1:49:31 PM fseventsd[46] failed to unlink old log file /Volumes/Backup of ivan/.fseventsd/00000000a678f6e3 (Invalid argument)
4/4/09 1:49:31 PM fseventsd[46] failed to unlink old log file /Volumes/Backup of ivan/.fseventsd/00000000a679605c (Invalid argument)
4/4/09 1:49:31 PM fseventsd[46] failed to unlink old log file /Volumes/Backup of ivan/.fseventsd/00000000a679c0df (Invalid argument)
[i]... several dozen more similar fseventsd messages...[/i]
4/4/09 1:49:31 PM fseventsd[46] failed to create the uuid file /Volumes/Backup of ivan/.fseventsd/fseventsd-uuid
4/4/09 1:49:31 PM /System/Library/CoreServices/backupd[14036] Stopping backupd to allow ejection of backup destination disk!
4/4/09 1:55:44 PM [0x0-0xd00d].com.apple.finder[173] Unexpected reply from backup server - ignored
4/4/09 1:55:44 PM [0x0-0xc00c].com.apple.systemuiserver[172] Unexpected reply from backup server - ignored
4/4/09 1:55:44 PM [0x0-0x720720].com.apple.systempreferences[14089] Unexpected reply from backup server - ignoredWhen I attempt to examine the .sparsebundle file using hdiutil it gives a "Resource busy" error #35. In addition, Disk Warrior is having no luck accessing the .sparsebundle. It looks like it is completely hosed. I tried the trick of moving out the highest numbered "band" file, but no luck with that. I wonder if the network connection to the Time Capsule was lost at a critical moment in time, such as when directory information for the .sparsebundle was being updated.