New To Mac-Forums?

Welcome to our community! Join the discussion today by registering your FREE account. If you have any problems with the registration process, please contact us!

Get your questions answered by community gurus • Advice and insight from world-class Apple enthusiasts • Exclusive access to members-only contests, giveaways and deals

Join today!

 
Start a Discussion
 

Mac-Forums Brief

Subscribe to Mac-Forums Brief to receive special offers from Mac-Forums partners and sponsors

Join the conversation RSS
OS X - Operating System General OS operation information and support

OS 10.5 - Time machine forever "preparing" after software update


Post Reply New Thread Subscribe

 
Thread Tools
gpc

 
Member Since: Apr 04, 2008
Posts: 9
gpc is on a distinguished road
Mac Specs: MacBookPro6,2 - Intel Core i7 - 2.66 GHz - 8 GB RAM

gpc is offline
Yesterday I allowed software update to update the following:

• Quicktime 7.5
• Digital Raw Camera Compatibility 2.1

The next time Time Machine ran, it appeared to back up about 160 MB (I could be misremembering the number) but instead of finishing, it immediately started "preparing" again. Now it never stops "preparing."

There's no disk activity on the backup drive or the main drive, but Finder uses about 30% of CPU when it's "preparing."

I've tried leaving it to run for hours on end, with no luck. I've tried various combinations of turning Time machine off, dismounting and remounting the backup drive, restarting the Mac, turning Time Machine back on...

When I enter Time Machine, the most recent backup is the one from before the software update. There's no sign of anything backed up more recently.

Anyone have any ideas?

MacBook4,1, 4 GB memory, OSX 10.5.3. Backup drive is on a USB and has about 30 GB available out of 120. There's about 50 GB on the main drive to be included in backups.
QUOTE Thanks
walmartconnect

 
walmartconnect's Avatar
 
Member Since: Oct 13, 2006
Location: Blacksburg, VA
Posts: 724
walmartconnect is a jewel in the roughwalmartconnect is a jewel in the roughwalmartconnect is a jewel in the rough
Mac Specs: 13'' Macbook w/ 2Ghz Core Duo, 2GB DDR2, 250GB HD, 10.5.4. iPod Touch.

walmartconnect is offline
gpc, what happens when you attempt to verify the permissions of this disk/perform a disk repair?

One last push!
QUOTE Thanks
gpc

 
Member Since: Apr 04, 2008
Posts: 9
gpc is on a distinguished road
Mac Specs: MacBookPro6,2 - Intel Core i7 - 2.66 GHz - 8 GB RAM

gpc is offline
Ah, good idea. But no, that hasn't worked. For the Time Machine drive, the buttons for Verify or Repair Disk Permissions are grayed out. I tried Verify Disk and Repair Disk, which were available, and after about 15 minutes it tells me the volume "appears to be OK."

I'll try verifying the permissions of the main drive next, altho I don't think that's what you meant...
QUOTE Thanks
gpc

 
Member Since: Apr 04, 2008
Posts: 9
gpc is on a distinguished road
Mac Specs: MacBookPro6,2 - Intel Core i7 - 2.66 GHz - 8 GB RAM

gpc is offline
Still no joy. Disk Utility did find thousands of permission problems on the main drive, all but half a dozen of them related to HP software. Fixed all those. Also ran Verify Disk on the main drive and learned the file and directory counts were each off by 1. Repaired that using Disk Utility booted from the Install DVD.

Started up TM again. It spent many minutes "preparing" with a significant amount of disk activity, which seemed promising. But now 40 minutes in, it is still "preparing," with no disk activity, Finder at about 20 CPU.
QUOTE Thanks
walmartconnect

 
walmartconnect's Avatar
 
Member Since: Oct 13, 2006
Location: Blacksburg, VA
Posts: 724
walmartconnect is a jewel in the roughwalmartconnect is a jewel in the roughwalmartconnect is a jewel in the rough
Mac Specs: 13'' Macbook w/ 2Ghz Core Duo, 2GB DDR2, 250GB HD, 10.5.4. iPod Touch.

walmartconnect is offline
Odd.

Can you post your system.log? Specifically, any line that contains "backupd". You can do this by opening up Console (Spotlight it), clicking on system.log and entering backupd in the search field on the top right.

One last push!
QUOTE Thanks
gpc

 
Member Since: Apr 04, 2008
Posts: 9
gpc is on a distinguished road
Mac Specs: MacBookPro6,2 - Intel Core i7 - 2.66 GHz - 8 GB RAM

gpc is offline
Here you go:

Jun 13 19:08:30 Pakari /System/Library/CoreServices/backupd[2174]: Waiting for index to be ready (915 > 0)
Jun 13 19:09:00 Pakari /System/Library/CoreServices/backupd[2174]: Waiting for index to be ready (915 > 0)
Jun 13 19:09:30 Pakari /System/Library/CoreServices/backupd[2174]: Waiting for index to be ready (915 > 0)
Jun 13 19:10:00 Pakari /System/Library/CoreServices/backupd[2174]: Waiting for index to be ready (915 > 0)
Jun 13 19:10:30 Pakari /System/Library/CoreServices/backupd[2174]: Waiting for index to be ready (915 > 0)
Jun 13 19:10:47 Pakari /System/Library/CoreServices/backupd[2174]: Copied 0 files (0 bytes) from volume Graham's Disk.
Jun 13 19:10:53 Pakari /System/Library/CoreServices/backupd[2174]: Backup canceled.


There are hundreds of lines of the "waiting," every 30 seconds going to back to whenever I last tried turning on TM. 19:10:47 must be when I turned it off again a short while ago.

And here is what goes into the log when I turn it on and manually start a backup:

Jun 13 19:26:07 Pakari /System/Library/CoreServices/backupd[3587]: Backup requested by user
Jun 13 19:26:07 Pakari /System/Library/CoreServices/backupd[3587]: Starting standard backup
Jun 13 19:26:07 Pakari /System/Library/CoreServices/backupd[3587]: Backing up to: /Volumes/Time Machine Pakari-1/Backups.backupdb
Jun 13 19:26:08 Pakari /System/Library/CoreServices/backupd[3587]: Event store UUIDs don't match for volume: Graham's Disk
Jun 13 19:26:08 Pakari /System/Library/CoreServices/backupd[3587]: Node requires deep traversal:/ reason:kFSEDBEventFlagMustScanSubDirs|kFSEDBEventF lagReasonEventDBUntrustable|


"Time Machine Pakari-1" is the backup drive's name. It has been chattering away at a lowish level of activity for about 8 minutes now, with no new entries in the log (I've hit "reload"). Still "preparing..."
QUOTE Thanks
gpc

 
Member Since: Apr 04, 2008
Posts: 9
gpc is on a distinguished road
Mac Specs: MacBookPro6,2 - Intel Core i7 - 2.66 GHz - 8 GB RAM

gpc is offline
And here's where the "waiting" lines begin, coinciding with the time that the backup drive stops its activity:

Jun 13 19:26:08 Pakari /System/Library/CoreServices/backupd[3587]: Node requires deep traversal:/ reason:kFSEDBEventFlagMustScanSubDirs|kFSEDBEventF lagReasonEventDBUntrustable|
Jun 13 19:38:39 Pakari /System/Library/CoreServices/backupd[3587]: No pre-backup thinning needed: 1.89 GB requested (including padding), 28.96 GB available
Jun 13 19:38:39 Pakari /System/Library/CoreServices/backupd[3587]: Waiting for index to be ready (915 > 0)
Jun 13 19:39:09: --- last message repeated 2 times ---
Jun 13 19:39:09 Pakari /System/Library/CoreServices/backupd[3587]: Waiting for index to be ready (915 > 0)
Jun 13 19:39:39: --- last message repeated 1 time ---
Jun 13 19:39:54 Pakari /System/Library/CoreServices/backupd[3587]: Waiting for index to be ready (915 > 0)
Jun 13 19:40:24 Pakari /System/Library/CoreServices/backupd[3587]: Waiting for index to be ready (915 > 0)
QUOTE Thanks
walmartconnect

 
walmartconnect's Avatar
 
Member Since: Oct 13, 2006
Location: Blacksburg, VA
Posts: 724
walmartconnect is a jewel in the roughwalmartconnect is a jewel in the roughwalmartconnect is a jewel in the rough
Mac Specs: 13'' Macbook w/ 2Ghz Core Duo, 2GB DDR2, 250GB HD, 10.5.4. iPod Touch.

walmartconnect is offline
Waiting for the index to be ready huh?

Time Machine may be waiting for some reason on Spotlight (just speculation). Have you tried to turn off indexing for your Time Machine drive as well as your primary drive? Give it a shot, it wouldn't hurt anything.

One last push!
QUOTE Thanks
walmartconnect

 
walmartconnect's Avatar
 
Member Since: Oct 13, 2006
Location: Blacksburg, VA
Posts: 724
walmartconnect is a jewel in the roughwalmartconnect is a jewel in the roughwalmartconnect is a jewel in the rough
Mac Specs: 13'' Macbook w/ 2Ghz Core Duo, 2GB DDR2, 250GB HD, 10.5.4. iPod Touch.

walmartconnect is offline
Make sure that you turn off Time Machine before you do any of that, just to be safe.

One last push!
QUOTE Thanks
gpc

 
Member Since: Apr 04, 2008
Posts: 9
gpc is on a distinguished road
Mac Specs: MacBookPro6,2 - Intel Core i7 - 2.66 GHz - 8 GB RAM

gpc is offline
Nope, that hasn't helped, but it looks as if something very odd is going on with the backup drive. I looked back through system.log to where the backup was first attempted after the Quicktime/Raw Camera software update. There's a slew of errors there including warnings about low disk space on the backup drive.

So I ran verify disk again. Again OK. But in system.log, right after saying everything is OK with the disk, there are the low space and I/O errors again. Yet there is about 29 GB of free space on the volume.

Looking at the backup volume in Finder, I see the package for the backup that never finishes:
2008-06-10-184722.inProgress
Created back 4 days ago when this problem started, last modified when I last tried turning TM on earlier today.

Do you think it's safe to delete that package and see if TM recovers? Get Info reports it is 46 GB, about the same as it does for every completed backup folder above it. TM prefs reports there is 51 GB of total included files on the main drive.

Here are the system.log lines from the disk verify. disk2s5 is the volume "Time Machine Pakari-1". And yes, it is still excluded from Spotlight, despite the lines about "/Volumes/Time Machine Pakari-1/.Spotlight-V100..."

Jun 14 12:19:23 Pakari Disk Utility[4629]: **********
Jun 14 12:19:23 Pakari Disk Utility[4629]: Disk Utility started.
Jun 14 12:20:32 Pakari Disk Utility[4629]: Verifying volume “Time Machine Pakari-1”
Jun 14 12:20:32 Pakari Disk Utility[4629]: Starting verification tool:
Jun 14 12:20:33 Pakari Disk Utility[4629]: Checking Journaled HFS Plus volume.
Jun 14 12:20:33 Pakari Disk Utility[4629]: Detected a case-sensitive catalog.
Jun 14 12:20:33 Pakari Disk Utility[4629]: Checking Extents Overflow file.
Jun 14 12:20:33 Pakari Disk Utility[4629]: Checking Catalog file.
Jun 14 12:22:18 Pakari Disk Utility[4629]: Checking multi-linked files.
Jun 14 12:23:49 Pakari Disk Utility[4629]: Checking Catalog hierarchy.
Jun 14 12:29:00 Pakari Disk Utility[4629]: Checking Extended Attributes file.
Jun 14 12:29:52 Pakari Disk Utility[4629]: Checking multi-linked directories.
Jun 14 12:37:06 Pakari Disk Utility[4629]: Checking volume bitmap.
Jun 14 12:37:06 Pakari Disk Utility[4629]: Checking volume information.
Jun 14 12:37:06 Pakari Disk Utility[4629]: The volume Time Machine Pakari-1 appears to be OK.
Jun 14 12:37:07 Pakari Disk Utility[4629]: Verifying tool completed:
Jun 14 12:37:07 Pakari Disk Utility[4629]:
Jun 14 12:37:08: --- last message repeated 1 time ---
Jun 14 12:37:08 Pakari kernel[0]: jnl: disk2s5: flushing fs disk buffer returned 0x5
Jun 14 12:37:16 Pakari kernel[0]: disk2s5: I/O error.
Jun 14 12:37:16 Pakari kernel[0]:
Jun 14 12:37:23: --- last message repeated 1 time ---
Jun 14 12:37:23 Pakari kernel[0]: disk2s5: I/O error.
Jun 14 12:37:23 Pakari kernel[0]:
Jun 14 12:37:30: --- last message repeated 1 time ---
Jun 14 12:37:30 Pakari kernel[0]: disk2s5: I/O error.
Jun 14 12:37:30 Pakari kernel[0]:
Jun 14 12:37:30: --- last message repeated 1 time ---
Jun 14 12:37:30 Pakari mds[31]: (/Volumes/Time Machine Pakari-1/.Spotlight-V100/Store-V1/Stores/6F800D30-7E80-4A00-9EA0-A2F709A9F4E5)(Error) IndexGeneral in void setAttributes(si_set_attr_ctx*, Boolean, long unsigned int):Couldn't update index.
Jun 14 12:37:30 Pakari mds[31]: (/Volumes/Time Machine Pakari-1/.Spotlight-V100/Store-V1/Stores/6F800D30-7E80-4A00-9EA0-A2F709A9F4E5)(Error) IndexGeneral in notify_lowspace:low space for device 234881029 (/Volumes/Time Machine Pakari-1/.Spotlight-V100/Store-V1/Stores/6F800D30-7E80-4A00-9EA0-A2F709A9F4E5)
Jun 14 12:37:30 Pakari mds[31]: (Error) Volume: LOW DISK SPACE device:234881029
Jun 14 12:37:37 Pakari kernel[0]: disk2s5: I/O error.
Jun 14 12:37:37 Pakari kernel[0]:
Jun 14 12:37:43: --- last message repeated 1 time ---
Jun 14 12:37:43 Pakari kernel[0]: disk2s5: I/O error.
Jun 14 12:37:43 Pakari kernel[0]:
Jun 14 12:37:50: --- last message repeated 1 time ---
Jun 14 12:37:50 Pakari kernel[0]: disk2s5: I/O error.
Jun 14 12:37:50 Pakari kernel[0]:
Jun 14 12:37:50: --- last message repeated 1 time ---
Jun 14 12:37:50 Pakari mds[31]: (/Volumes/Time Machine Pakari-1/.Spotlight-V100/Store-V1/Stores/6F800D30-7E80-4A00-9EA0-A2F709A9F4E5)(Error) IndexGeneral in void setAttributes(si_set_attr_ctx*, Boolean, long unsigned int):Couldn't update index.
Jun 14 12:37:50 Pakari mds[31]: (/Volumes/Time Machine Pakari-1/.Spotlight-V100/Store-V1/Stores/6F800D30-7E80-4A00-9EA0-A2F709A9F4E5)(Error) IndexGeneral in notify_lowspace:low space for device 234881029 (/Volumes/Time Machine Pakari-1/.Spotlight-V100/Store-V1/Stores/6F800D30-7E80-4A00-9EA0-A2F709A9F4E5)
Jun 14 12:37:50 Pakari mds[31]: (Error) Volume: LOW DISK SPACE device:234881029
QUOTE Thanks
walmartconnect

 
walmartconnect's Avatar
 
Member Since: Oct 13, 2006
Location: Blacksburg, VA
Posts: 724
walmartconnect is a jewel in the roughwalmartconnect is a jewel in the roughwalmartconnect is a jewel in the rough
Mac Specs: 13'' Macbook w/ 2Ghz Core Duo, 2GB DDR2, 250GB HD, 10.5.4. iPod Touch.

walmartconnect is offline
[QUOTE=gpc;674503]Do you think it's safe to delete that package and see if TM recovers?

By now I would have reformatted the entire TM drive. If you aren't willing to do that though, certainly it wouldn't be the end of the world to delete that package. Hey, if you are worried about it, just save it on another hard drive (if you have space).

One last push!
QUOTE Thanks
gpc

 
Member Since: Apr 04, 2008
Posts: 9
gpc is on a distinguished road
Mac Specs: MacBookPro6,2 - Intel Core i7 - 2.66 GHz - 8 GB RAM

gpc is offline
The drive has all my backups since I bought this computer (3 months ago, switching over from WinXP) so I want to get another drive and have a good backup on it before I reformat this one.

BTW, I found this commentary on a very similar problem:

http://play-control.org/ewing/jibber...rors_and_.html

Quote:
On Time Machine Errors and Data Integrity...
[...]
I spoke with a DTS engineer after Cocoaheads, and he confirmed that Time Machine does not do any integrity checks.
QUOTE Thanks
walmartconnect

 
walmartconnect's Avatar
 
Member Since: Oct 13, 2006
Location: Blacksburg, VA
Posts: 724
walmartconnect is a jewel in the roughwalmartconnect is a jewel in the roughwalmartconnect is a jewel in the rough
Mac Specs: 13'' Macbook w/ 2Ghz Core Duo, 2GB DDR2, 250GB HD, 10.5.4. iPod Touch.

walmartconnect is offline
Indeed.

I had a similar integrity-based issue with my TM drive a month ago. Everything pointed to a simple repair of the disk, but Disk Manager would fail every time (can't remember why). A manual repair via "fsck_hfs -y" within the terminal did the trick however.

One last push!
QUOTE Thanks

Post Reply New Thread Subscribe


« Newbie Question | Install OSX 10.2 without a mouse »
Thread Tools

Currently Active Users Viewing This Thread: 1 (0 members and 1 guests)
 
Posting Rules
You may not post new threads
You may not post replies
You may not post attachments
You may not edit your posts
BB code is On
Smilies are On
[IMG] code is On
HTML code is Off
Trackbacks are On
Pingbacks are On
Refbacks are Off
Forum Jump

Similar Threads
Thread
Thread Starter
Forum
Replies
Last Post
New Time Capsule / Old Time Machine External Drive a1dadio Internet, Networking, and Wireless 0 04-28-2008 04:12 PM
Macbook and Time Machine Krelowyn OS X - Apps and Games 2 02-20-2008 04:58 PM
Time Machine and System Files mikegs OS X - Operating System 2 02-16-2008 05:57 PM
Time Machine MrChris OS X - Operating System 3 11-19-2007 11:54 PM
Time Machine Question ChaePod OS X - Operating System 1 10-11-2007 01:53 AM

All times are GMT -4. The time now is 09:12 PM.

Powered by vBulletin
Copyright ©2000 - 2014, Jelsoft Enterprises Ltd.
X

Welcome to Mac-Forums.com

Create your username to jump into the discussion!

New members like you have made this community the ultimate source for your Mac since 2003!


(4 digit year)

Already a member?