Time Machine backup very slow in Lion

Hi, Lion was slow in finding TYPE before I took looked. The name of my computer for SHARE setting is "Mac-OS-X -KUGS-C01" as Lion was hinding the extension for that one so I assume that is the active sparsebundle since I only backup one Mac. The other sparsebundle called "MacBook-01" must be for my old Macbook and I never deleted it. If this is the case, would Time Machine keeping "touching" it when backing up my curent macbook (i.e. the MODIFIED is updated when TM ends) and it is OK to just delete? It is less than half the size of the other one so more evidence it is the old one as I have only been adding data and apps. Thx

What's up with the rose text color of the time line on the right? I can barely read it.

I am having the same issues. Lost roughly 20 GB of free space and Time Machine ran for over 15 hours yesterday without completing a back up. Let me know if you find an answer.

@bwclifton: Welcome to the club. Installing Lion a second time gave me back all the disc space that got lost, but immediately disc space began to disappear again.

Don't know if the two issues (lost disc space & hour long Time Machine backups) are somehow related.

I already varified the hard disc of my Macbook and on time Disc Utility is varifiying and repairing the backup disc. Still some hours to go. So will have more information later on.

EarthBased wrote:

Hi, Lion was slow in finding TYPE before I took looked. The name of my computer for SHARE setting is "Mac-OS-X -KUGS-C01" as Lion was hinding the extension for that one so I assume that is the active sparsebundle since I only backup one Mac.

Yes, on occasion it's unable to put the "sparsebundle" extension on it.

The other sparsebundle called "MacBook-01" must be for my old Macbook and I never deleted it. If this is the case, would Time Machine keeping "touching" it when backing up my curent macbook (i.e. the MODIFIED is updated when TM ends) and it is OK to just delete?

It's probably touching it to be sure it's not the one for your current system. TM does not use the name of the sparsebundle, but a file inside it to know which Mac it belongs to. That's so, if you change your Computer name, or have two with the same name, it still knows which is which. And if you change your computer name, it will change the sparsebundle name automatically on the next backup.

But yes, if you don't need those backups any more, you can delete it. See #Q5 in Using Time Machine with a Time Capsule.

Exact same situation for me. I did a complete reinstall of Lion on Friday. Got back the hard drive space, but it went away almost immediately when I plugged in my external hard drive for backup.

I'm making my 4th trip to an Apple store since Friday to see if they can fix the hard drive space mysteriously disappearing, as well as get Time Machine working properly.

If they give me an answer, I will pass it along.

Have you tried the things in Where did my Disk Space go?

bwclifton wrote:

Exact same situation for me. I did a complete reinstall of Lion on Friday. Got back the hard drive space, but it went away almost immediately when I plugged in my external hard drive for backup.

I'm making my 4th trip to an Apple store since Friday to see if they can fix the hard drive space mysteriously disappearing, as well as get Time Machine working properly.

If they give me an answer, I will pass it along.

Time Machine has a new feature (see below) Not sure if it applies to your situation.

Try backing up to TM again, but leave the TM Volume connected for a few hours

If you have a portable computer, in addition to saving backups on your backup disk, Time Machine saves hourly snapshots of files and stores the snapshots on your computer’s internal drive. If your backup disk isn’t connected, Time Machine continues saving snapshots on your internal drive, and then resumes backing up to your backup disk when you reconnect it.

Tony

Edit: Just read Pondini's write-up on "Local Snapshots". Excellent, take a look at his item #30

@bwclifton That would be nice. Hope they will find the reason for this strange behaviour.

Here Disc Utility is still verifiying and repairing my backup disc. Doing it for hours now and still 7 hours to go, but found already several errors.

@ Tony Thanks. I know about the local Snapshot features and guess bwclifton also does.

But hard to believe that this feature would occupy more than 20 GB within a couple of days.

Disabled the Snapshot feature with the terminal last week, but disc space still vanished.

Verifying and repairing my external backup drive took more than 16 hours. There were several errors but in the end Disc Utility told me that there aren't any problems anymore.

First Time Machine backup took about 40 minutes but was nearly 1 GB. Second backup was very fast, as was the third one. Fourth backup got stuck again at 140 KB and finished when I was away. So far now Time Machine backups last a bit longer than under Snow Leopard, but they are still reasonable. Not taking several hours and they all finished so far.

Thank you Pondini for your posts and your website!

Georg Portenkirchner wrote:

Verifying and repairing my external backup drive took more than 16 hours.

Just for future reference, there's no point to running the verify; if a problem is found, that all has to be done again by the repair. Just run repair.

Anyhowk glad it's sorted out! 🙂

Disabled TM, deleted Spotlight Index after stopping Spotlight and emptied Trash of it, started Spotlight, Enabled TM, Started a backup. I also deleted the sparsebunble of my old macbook per other post. Looks like I still have some problems; what is AFP RPC? I assume that is MDS trying to talk to BACKUPD? Anyway here is the log and let me know if I should just wipe TM clean and started over. Thx

BACKUPD:

8/2/11 8:27:50.773 AM com.apple.backupd: Starting standard backup

8/2/11 8:27:53.360 AM com.apple.backupd: Attempting to mount network destination URL: afp://Dr@Time-Capsule-KS.local/Data

8/2/11 8:28:01.749 AM com.apple.backupd: Mounted network destination at mountpoint: /Volumes/Data using URL: afp://D@Time-Capsule-KS.local/Data

8/2/11 8:28:05.952 AM com.apple.backupd: QUICKCHECK ONLY; FILESYSTEM CLEAN

8/2/11 8:28:09.027 AM com.apple.backupd: Disk image /Volumes/Data/Mac-OS-X -KUGS-C01.sparsebundle mounted at: /Volumes/Time Machine Backups

8/2/11 8:28:09.037 AM com.apple.backupd: Backing up to: /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 8:29:50.242 AM com.apple.backupd: Backup canceled.

8/2/11 8:29:58.941 AM com.apple.backupd: Ejected Time Machine disk image.

8/2/11 8:29:59.342 AM com.apple.backupd: Ejected Time Machine network volume.

8/2/11 9:14:33.233 AM mds: (Normal) DiskStore: Rebuilding index for /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 9:15:29.914 AM com.apple.backupd: Starting standard backup

8/2/11 9:15:32.013 AM com.apple.backupd: Attempting to mount network destination URL: afp://Dar@Time-Capsule-KS.local/Data

8/2/11 9:15:32.601 AM com.apple.backupd: Mounted network destination at mountpoint: /Volumes/Data-1 using URL: afp://Dtar@Time-Capsule-KS.local/Data

8/2/11 9:15:34.055 AM com.apple.backupd: Disk image already attached: /Volumes/Data-1/Mac-OS-X -KUGS-C01.sparsebundle, DIHLDiskImageAttach returned: 35

8/2/11 9:15:35.281 AM com.apple.backupd: Disk image already attached: /Volumes/Data-1/Mac-OS-X -KUGS-C01.sparsebundle, DIHLDiskImageAttach returned: 35

8/2/11 9:15:36.525 AM com.apple.backupd: Failed to mount disk image: Error Domain=com.apple.backupd.ErrorDomain Code=31 "The operation couldn’t be completed. (com.apple.backupd.ErrorDomain error 31.)" UserInfo=0x1018449a0 {MessageParameters=(

"/Volumes/Data-1/Mac-OS-X -KUGS-C01.sparsebundle"

)}

8/2/11 9:15:36.795 AM com.apple.backupd: Ejected Time Machine network volume.

8/2/11 9:15:36.797 AM com.apple.backupd: Waiting 60 seconds and trying again.

8/2/11 9:16:37.358 AM com.apple.backupd: Attempting to mount network destination URL: afp://Dar@Time-Capsule-KS.local/Data

8/2/11 9:16:37.922 AM com.apple.backupd: Mounted network destination at mountpoint: /Volumes/Data-1 using URL: afp://Dtar@Time-Capsule-KS.local/Data

8/2/11 9:16:39.353 AM com.apple.backupd: Disk image already attached: /Volumes/Data-1/Mac-OS-X -KUGS-C01.sparsebundle, DIHLDiskImageAttach returned: 35

8/2/11 9:16:40.579 AM com.apple.backupd: Disk image already attached: /Volumes/Data-1/Mac-OS-X -KUGS-C01.sparsebundle, DIHLDiskImageAttach returned: 35

8/2/11 9:16:41.795 AM com.apple.backupd: Failed to mount disk image: Error Domain=com.apple.backupd.ErrorDomain Code=31 "The operation couldn’t be completed. (com.apple.backupd.ErrorDomain error 31.)" UserInfo=0x7fec18514fc0 {MessageParameters=(

"/Volumes/Data-1/Mac-OS-X -KUGS-C01.sparsebundle"

)}

8/2/11 9:16:42.051 AM com.apple.backupd: Ejected Time Machine network volume.

8/2/11 9:16:42.054 AM com.apple.backupd: Waiting 60 seconds and trying again.

8/2/11 9:17:42.352 AM com.apple.backupd: Attempting to mount network destination URL: afp://n%20r@Time-Capsule-KS.local/Data

8/2/11 9:17:42.829 AM com.apple.backupd: Mounted network destination at mountpoint: /Volumes/Data-1 using URL: afp://n%20r@Time-Capsule-KS.local/Data

8/2/11 9:17:44.313 AM com.apple.backupd: Disk image already attached: /Volumes/Data-1/Mac-OS-X -KUGS-C01.sparsebundle, DIHLDiskImageAttach returned: 35

8/2/11 9:17:45.550 AM com.apple.backupd: Disk image already attached: /Volumes/Data-1/Mac-OS-X -KUGS-C01.sparsebundle, DIHLDiskImageAttach returned: 35

8/2/11 9:17:46.754 AM com.apple.backupd: Failed to mount disk image: Error Domain=com.apple.backupd.ErrorDomain Code=31 "The operation couldn’t be completed. (com.apple.backupd.ErrorDomain error 31.)" UserInfo=0x7fec1851caf0 {MessageParameters=(

"/Volumes/Data-1/Mac-OS-X -KUGS-C01.sparsebundle"

)}

8/2/11 9:17:46.998 AM com.apple.backupd: Ejected Time Machine network volume.

8/2/11 9:17:46.999 AM com.apple.backupd: Giving up after 3 retries.

8/2/11 9:17:57.052 AM com.apple.backupd: Backup failed with error: 31

8/2/11 9:18:21.910 AM com.apple.backupd: Starting standard backup

8/2/11 9:18:21.918 AM com.apple.backupd: Attempting to mount network destination URL: afp://x%x@Time-Capsule-KS.local/Data

8/2/11 9:18:22.429 AM com.apple.backupd: Mounted network destination at mountpoint: /Volumes/Data-1 using URL: afp://ar@Time-Capsule-KS.local/Data

8/2/11 9:18:29.032 AM com.apple.backupd: QUICKCHECK ONLY; FILESYSTEM CLEAN

8/2/11 9:18:31.701 AM com.apple.backupd: Disk image /Volumes/Data-1/Mac-OS-X -KUGS-C01.sparsebundle mounted at: /Volumes/Time Machine Backups

8/2/11 9:18:31.849 AM com.apple.backupd: Backing up to: /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 9:18:34.731 AM mds: (Normal) DiskStore: Rebuilding index for /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 9:18:49.545 AM com.apple.backupd: Waiting for index to be ready (100)

8/2/11 9:19:49.629 AM com.apple.backupd: Waiting for index to be ready (100)

8/2/11 9:20:49.705 AM com.apple.backupd: Waiting for index to be ready (100)

8/2/11 9:21:49.827 AM com.apple.backupd: Waiting for index to be ready (100)

8/2/11 9:22:49.916 AM com.apple.backupd: Waiting for index to be ready (100)

8/2/11 9:23:50.026 AM com.apple.backupd: Waiting for index to be ready (100)

8/2/11 9:24:50.124 AM com.apple.backupd: Waiting for index to be ready (100)

8/2/11 9:25:50.218 AM com.apple.backupd: Waiting for index to be ready (100)

8/2/11 9:26:50.308 AM com.apple.backupd: Waiting for index to be ready (100)

8/2/11 9:27:50.402 AM com.apple.backupd: Waiting for index to be ready (100)

8/2/11 9:28:50.504 AM com.apple.backupd: Waiting for index to be ready (100)

8/2/11 9:30:43.910 AM com.apple.backupd: 1.35 GB required (including padding), 375.35 GB available

8/2/11 9:51:24.439 AM com.apple.backupd: Copied 24780 files (1.1 GB) from volume Macintosh HD.

8/2/11 9:51:48.325 AM com.apple.backupd: 474.8 MB required (including padding), 375.35 GB available

8/2/11 9:53:10.850 AM com.apple.backupd: Copied 1328 files (37.5 MB) from volume Macintosh HD.

8/2/11 9:55:28.785 AM com.apple.backupd: Starting post-backup thinning

8/2/11 9:57:37.623 AM com.apple.backupd: Deleted /Volumes/Time Machine Backups/Backups.backupdb/Mac-OS-X -KUGS-C01/2011-07-03-091925 (128.6 MB)

8/2/11 9:59:49.593 AM com.apple.backupd: Deleted /Volumes/Time Machine Backups/Backups.backupdb/Mac-OS-X -KUGS-C01/2011-07-30-221012 (208.0 MB)

8/2/11 9:59:49.593 AM com.apple.backupd: Post-back up thinning complete: 2 expired backups removed

8/2/11 9:59:51.758 AM com.apple.backupd: Backup completed successfully.

8/2/11 9:59:51.768 AM com.apple.backupd: Waiting for Spotlight to finish indexing /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 10:00:51.780 AM com.apple.backupd: Waiting for Spotlight to finish indexing /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 10:02:51.798 AM com.apple.backupd: Waiting for Spotlight to finish indexing /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 10:05:59.186 AM com.apple.backupd: Waiting for Spotlight to finish indexing /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 10:09:59.237 AM com.apple.backupd: Waiting for Spotlight to finish indexing /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 10:14:59.286 AM com.apple.backupd: Waiting for Spotlight to finish indexing /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 10:15:53.596 AM com.apple.backupd: Starting standard backup

8/2/11 10:15:54.048 AM com.apple.backupd: Network destination already mounted at: /Volumes/Data-1

8/2/11 10:15:56.246 AM com.apple.backupd: Disk image already attached: /Volumes/Data-1/Mac-OS-X -KUGS-C01.sparsebundle, DIHLDiskImageAttach returned: 35

8/2/11 10:15:57.493 AM com.apple.backupd: Disk image already attached: /Volumes/Data-1/Mac-OS-X -KUGS-C01.sparsebundle, DIHLDiskImageAttach returned: 35

8/2/11 10:15:58.710 AM com.apple.backupd: Disk image /Volumes/Data-1/Mac-OS-X -KUGS-C01.sparsebundle mounted at: /Volumes/Time Machine Backups

8/2/11 10:15:58.873 AM com.apple.backupd: Backing up to: /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 10:18:04.924 AM com.apple.backupd: 668.7 MB required (including padding), 375.35 GB available

8/2/11 10:20:59.366 AM com.apple.backupd: Waiting for Spotlight to finish indexing /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 10:21:29.499 AM com.apple.backupd: Copied 1154 files (37.3 MB) from volume Macintosh HD.

8/2/11 10:21:31.054 AM com.apple.backupd: 786.1 MB required (including padding), 375.35 GB available

8/2/11 10:22:49.512 AM com.apple.backupd: Copied 938 files (33.9 MB) from volume Macintosh HD.

8/2/11 10:23:01.152 AM com.apple.backupd: Starting post-backup thinning

8/2/11 10:25:59.321 AM com.apple.backupd: Deleted /Volumes/Time Machine Backups/Backups.backupdb/Mac-OS-X -KUGS-C01/2011-07-31-222140 (220.0 MB)

8/2/11 10:25:59.321 AM com.apple.backupd: Post-back up thinning complete: 1 expired backups removed

8/2/11 10:26:01.197 AM com.apple.backupd: Backup completed successfully.

8/2/11 10:28:01.890 AM com.apple.backupd: Waiting for Spotlight to finish indexing /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 10:36:06.164 AM com.apple.backupd: Waiting for Spotlight to finish indexing /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 10:45:06.266 AM com.apple.backupd: Waiting for Spotlight to finish indexing /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 10:55:06.375 AM com.apple.backupd: Waiting for Spotlight to finish indexing /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 10:59:15.700 AM mdworker: iCal.mdimporter: could not find component keys: /Volumes/Time Machine Backups/Backups.backupdb/Mac-OS-X -KUGS-C01/2010-12-05-094040/Macintosh HD/Applications/Zimbra Desktop/data/conf/timezones.ics

8/2/11 11:05:06.493 AM com.apple.backupd: Waiting for Spotlight to finish indexing /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 11:14:33.662 AM mdworker: iCal.mdimporter: could not find component keys: /Volumes/Time Machine Backups/Backups.backupdb/Mac-OS-X -KUGS-C01/2010-12-05-094040/Macintosh HD/Users/danstar/Library/Zimbra Desktop/conf/timezones.ics

8/2/11 11:15:06.603 AM com.apple.backupd: Waiting for Spotlight to finish indexing /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 11:15:53.425 AM com.apple.backupd: Starting standard backup

8/2/11 11:15:55.018 AM com.apple.backupd: Network destination already mounted at: /Volumes/Data-1

8/2/11 11:15:58.405 AM com.apple.backupd: Disk image already attached: /Volumes/Data-1/Mac-OS-X -KUGS-C01.sparsebundle, DIHLDiskImageAttach returned: 35

8/2/11 11:16:01.211 AM com.apple.backupd: Disk image already attached: /Volumes/Data-1/Mac-OS-X -KUGS-C01.sparsebundle, DIHLDiskImageAttach returned: 35

8/2/11 11:16:02.433 AM com.apple.backupd: Disk image /Volumes/Data-1/Mac-OS-X -KUGS-C01.sparsebundle mounted at: /Volumes/Time Machine Backups

8/2/11 11:16:02.574 AM com.apple.backupd: Backing up to: /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 11:17:52.324 AM com.apple.backupd: 1.84 GB required (including padding), 375.35 GB available

8/2/11 11:17:52.326 AM com.apple.backupd: Waiting for index to be ready (100)

8/2/11 11:18:52.404 AM com.apple.backupd: Waiting for index to be ready (100)

8/2/11 11:19:53.365 AM com.apple.backupd: Waiting for index to be ready (100)

8/2/11 11:20:53.882 AM com.apple.backupd: Waiting for index to be ready (100)

8/2/11 11:25:03.345 AM com.apple.backupd: Copied 1460 files (39.5 MB) from volume Macintosh HD.

8/2/11 11:25:04.405 AM com.apple.backupd: 1.72 GB required (including padding), 375.35 GB available

8/2/11 11:25:06.697 AM com.apple.backupd: Waiting for Spotlight to finish indexing /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 11:25:40.903 AM com.apple.backupd: Copied 651 files (16 KB) from volume Macintosh HD.

8/2/11 11:25:56.959 AM com.apple.backupd: Starting post-backup thinning

8/2/11 11:25:56.959 AM com.apple.backupd: No post-back up thinning needed: no expired backups exist

8/2/11 11:25:58.168 AM com.apple.backupd: Backup completed successfully.

8/2/11 11:35:07.027 AM com.apple.backupd: Waiting for Spotlight to finish indexing /Volumes/Time Machine Backups/Backups.backupdb

MDS:

8/2/11 8:28:01.749 AM mds: ERROR: _MDSChannelInitForAFP: AFPSendSpotLightRPC failed -1

8/2/11 8:29:50.133 AM mds: (Error) Volume: Could not find requested backup type:2 for volume

8/2/11 8:33:55.956 AM mds: ERROR: _MDSChannelInitForAFP: AFPSendSpotLightRPC failed -1

8/2/11 8:38:33.092 AM mds: (Normal) FMW: DOUBLE SLASH Rename (source) - p:11 ac:4 am:51c1 t:6163499850120

8/2/11 8:38:33.092 AM mds: (Error) FMW: DOUBLE SLASH Rename (destination) - p:11 ac:4 am:51c1 t:6163499850120

8/2/11 8:38:48.183 AM mds: (Normal) FMW: DOUBLE SLASH Delete - p:11 ac:3 am:5c1 t:6178595586586

8/2/11 8:39:53.094 AM sandboxd: ([38]) mds(38) deny file-write-data /dev/dtracehelper

8/2/11 8:39:53.136 AM sandboxd: ([38]) mds(38) deny file-issue-extension /Volumes/Data/Mac-OS-X -KUGS-C01.sparsebundle

8/2/11 8:39:53.137 AM mds: Bookmark failed to issue extension for item /Volumes/Data/Mac-OS-X -KUGS-C01.sparsebundle (depth=4000): Operation not permitted

8/2/11 8:42:54.889 AM mds: (Normal) FMW: FMW 0 0

8/2/11 8:50:35.651 AM mds: ERROR: _MDSChannelInitForAFP: AFPSendSpotLightRPC failed -1

8/2/11 8:55:15.394 AM shutdown_stall_reporter: Saved shutdownStall report for mds version ??? to /Library/Logs/DiagnosticReports/mds_2011-08-02-085515_Mac-OS-X-KUGS-C01.shutdow nStall

8/2/11 8:56:47.301 AM mds: ERROR: _MDSChannelInitForAFP: AFPSendSpotLightRPC failed -1

8/2/11 8:56:57.015 AM sandboxd: ([36]) mds(36) deny file-write-create /Volumes/Time Machine Backups/.Trashes/501/.Spotlight-V100 8.51.19 AM/Store-V2/F135B892-5A45-4463-ABD0-77E466A5AACD/shutdown_time

8/2/11 8:56:57.100 AM sandboxd: ([36]) mds(36) deny file-write-create /Volumes/Time Machine Backups/.Trashes/501/.Spotlight-V100 8.51.19 AM/Store-V2/F135B892-5A45-4463-ABD0-77E466A5AACD/0.indexUpdates

8/2/11 8:56:57.145 AM sandboxd: ([36]) mds(36) deny file-write-create /Volumes/Time Machine Backups/.Trashes/501/.Spotlight-V100 8.51.19 AM/Store-V2/F135B892-5A45-4463-ABD0-77E466A5AACD/shutdown_time

8/2/11 9:09:45.687 AM mds: ERROR: _MDSChannelInitForAFP: AFPSendSpotLightRPC failed -1

8/2/11 9:10:21.845 AM mds: ERROR: _MDSChannelInitForAFP: AFPSendSpotLightRPC failed -1

8/2/11 9:12:07.377 AM mds: ERROR: _MDSChannelInitForAFP: AFPSendSpotLightRPC failed -1

8/2/11 9:14:30.703 AM mds: (Normal) DiskStore: Reindexing /Volumes/Time Machine Backups/.Spotlight-V100/Store-V2/F135B892-5A45-4463-ABD0-77E466A5AACD because no basetime was found.

8/2/11 9:14:33.233 AM mds: (Normal) DiskStore: Rebuilding index for /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 9:15:32.741 AM mds: ERROR: _MDSChannelInitForAFP: AFPSendSpotLightRPC failed -1

8/2/11 9:16:38.041 AM mds: ERROR: _MDSChannelInitForAFP: AFPSendSpotLightRPC failed -1

8/2/11 9:17:42.996 AM mds: ERROR: _MDSChannelInitForAFP: AFPSendSpotLightRPC failed -1

8/2/11 9:18:22.431 AM mds: ERROR: _MDSChannelInitForAFP: AFPSendSpotLightRPC failed -1

8/2/11 9:18:31.734 AM mds: (Normal) DiskStore: Reindexing /Volumes/Time Machine Backups/.Spotlight-V100/Store-V2/F135B892-5A45-4463-ABD0-77E466A5AACD because no basetime was found.

8/2/11 9:18:34.731 AM mds: (Normal) DiskStore: Rebuilding index for /Volumes/Time Machine Backups/Backups.backupdb

8/2/11 9:55:28.785 AM mds: (Error) Volume: Could not find requested backup type:2 for volume

8/2/11 10:04:09.146 AM sandboxd: ([36]) mds(36) deny file-write-data /dev/dtracehelper

8/2/11 10:23:01.151 AM mds: (Error) Volume: Could not find requested backup type:2 for volume

8/2/11 11:25:56.958 AM mds: (Error) Volume: Could not find requested backup type:2 for volume

EarthBased wrote:

Disabled TM, deleted Spotlight Index after stopping Spotlight and emptied Trash of it

What did you delete?

8/2/11 9:14:33.233 AM mds: (Normal) DiskStore: Rebuilding index for /Volumes/Time Machine Backups/Backups.backupdb

That's the problem; there are several similar messages.

let me know if I should just wipe TM clean and started over

That's the easiest thing to do. See #Q5 in Using Time Machine with a Time Capsule.

The other is to delete the index, so it will be completely rebuilt on the next backup:

First, download the Tinker Tool app, per #A3 in Time Machine - Troubleshooting. Follow the instructions there to show invisible files.

Via the Finder, locate the sparse bundle, and double-click it to mount the disk image inside it. It will appear on your desktop or Finder sidebar, and is usually named "Time Machine Backups."

Double-click it to open it. Inside it you'll find a .Spotlight-V100 folder. Delete it (you'll have to enter your Admin password), and empty the trash. Do not touch anything else.

Eject the disk image, and run a backup. The first one will take quite a while, as it must be indexed, but thereafter, backups should run normally.

I agree with everyone that backups are now painfully slower under Lion, for some reason. I have read the posts here and have run disk utility on both my macbookpro hard drive as well as the Time Machine backups volume and everything is fine. I have disconnected my extended network and that didn't make any difference. I let Spotlight finish indexing. And yet,the backup, which it says is less than 200MB takes forever to finish. I have attached a snippet form the console log.

While I watch it backing up, I definitely see a lot more data being transferred than the 200MB. I'm wondering if the sparse data files are coming into play. Perhaps Time Machine is somehow now expanding the sparse datasets and then transferring all that data now, where under Snow Leopard it didn't ahve to do that.

Any other thoughts from anyone on what to try? I haven't deleted all the backups and created a new one yet, but that seems like a drastic measure. I would hope Apple would say something first.

Here is the snippet from my system log, having filtered on backupd. You can see that it took over an hour.

Aug 2 09:31:18 MacbookPro com.apple.backupd[914]: Starting standard backup

Aug 2 09:31:19 MacbookPro com.apple.backupd[914]: Attempting to mount network destination URL: afp://Daniel%20Wieder@imac._afpovertcp._tcp.local/TM%20Backups%20MacBookPro

Aug 2 09:31:20 MacbookPro com.apple.backupd[914]: Mounted network destination at mountpoint: /Volumes/TM Backups MacBookPro-1 using URL: afp://Daniel%20Wieder@imac._afpovertcp._tcp.local/TM%20Backups%20MacBookPro

Aug 2 09:31:55 MacbookPro com.apple.backupd[914]: QUICKCHECK ONLY; FILESYSTEM CLEAN

Aug 2 09:32:03 MacbookPro com.apple.backupd[914]: Disk image /Volumes/TM Backups MacBookPro-1/MacbookPro.sparsebundle mounted at: /Volumes/Time Machine Backups

Aug 2 09:32:04 MacbookPro com.apple.backupd[914]: Backing up to: /Volumes/Time Machine Backups/Backups.backupdb

Aug 2 09:37:17 MacbookPro com.apple.backupd[914]: 221.9 MB required (including padding), 265.91 GB available

Aug 2 09:56:31 MacbookPro com.apple.backupd[914]: Copied 3386 files (165.2 MB) from volume Macintosh HD.

Aug 2 09:58:26 MacbookPro com.apple.backupd[914]: 100.0 MB required (including padding), 265.91 GB available

Aug 2 10:08:08 MacbookPro com.apple.backupd[914]: Copied 1394 files (673 KB) from volume Macintosh HD.

Aug 2 10:10:57 MacbookPro com.apple.backupd[914]: Starting post-backup thinning

Aug 2 10:34:15 MacbookPro com.apple.backupd[914]: Deleted /Volumes/Time Machine Backups/Backups.backupdb/MacbookPro/2011-07-03-200711 (190.9 MB)

Aug 2 10:34:15 MacbookPro com.apple.backupd[914]: Post-back up thinning complete: 1 expired backups removed

Aug 2 10:34:16 MacbookPro com.apple.backupd[914]: Backup completed successfully.

Aug 2 10:34:16 MacbookPro com.apple.backupd[914]: Waiting for Spotlight to finish indexing /Volumes/Time Machine Backups/Backups.backupdb

Aug 2 10:35:16 MacbookPro com.apple.backupd[914]: Waiting for Spotlight to finish indexing /Volumes/Time Machine Backups/Backups.backupdb