You can make a difference in the Apple Support Community!

When you sign up with your Apple Account, you can provide valuable feedback to other community members by upvoting helpful replies and User Tips.

Looks like no one’s replied in a while. To start the conversation again, simply ask a new question.

Time Machine always requires deep traversal?

Hi all-


For the tl;dr crowd: every time my Mac Pro starts a backup, it does a deep traversal (this has gone on for about two months since I got the drive). I'm backing up to a 2TB USB external drive that is always connected. I've added the backup drive to Spotlight's exclude/privacy list. Yesterday I rebooted the machine using the OSX installation disk and performed disk repairs on both the system drive and the backup drive (neither was found to have any errors). I don't have clamXav or any other AV installed. What else can I try to avoid the deep traversal? Here are the console logs for two back-to-back backups (note that these are just before I got into the office, so I don't think user interaction is forcing the deep traversal):


5/31/12 6:53:32 AM
com.apple.backupd[5425]
Starting standard backup
5/31/12 6:53:33 AM
com.apple.backupd[5425]
Backing up to: /Volumes/Time Machine Backups/Backups.backupdb
5/31/12 6:55:00 AM
com.apple.backupd[5425]
Node requires deep traversal:/ reason:contains changes|must scan subdirs|fsevent|
5/31/12 6:57:11 AM
_spotlight[5455]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 6:57:11 AM
_spotlight[5460]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 6:57:21 AM
com.apple.backupd[5425]
No pre-backup thinning needed: 1.87 GB requested (including padding), 1.36 TB available
5/31/12 6:57:23 AM
_spotlight[5466]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 6:57:23 AM
_spotlight[5471]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 6:57:23 AM
_spotlight[5476]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 6:57:23 AM
_spotlight[5481]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 6:57:34 AM
com.apple.backupd[5425]
Copied 2085 files (17.3 MB) from volume Snow Leopard.
5/31/12 6:59:02 AM
com.apple.backupd[5425]
Node requires deep traversal:/ reason:contains changes|must scan subdirs|fsevent|
5/31/12 7:01:05 AM
_spotlight[5498]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 7:01:05 AM
_spotlight[5503]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 7:01:13 AM
com.apple.backupd[5425]
No pre-backup thinning needed: 1.87 GB requested (including padding), 1.36 TB available
5/31/12 7:01:15 AM
_spotlight[5508]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 7:01:15 AM
_spotlight[5513]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 7:01:15 AM
_spotlight[5518]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 7:01:15 AM
_spotlight[5523]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 7:01:26 AM
com.apple.backupd[5425]
Copied 2085 files (16.9 MB) from volume Snow Leopard.
5/31/12 7:02:57 AM
com.apple.backupd[5425]
Starting post-backup thinning
5/31/12 7:32:41 AM
com.apple.backupd[5425]
Deleted backup /Volumes/Time Machine Backups/Backups.backupdb/warp/2012-05-29-143904: 1.36 TB now available
5/31/12 7:32:41 AM
com.apple.backupd[5425]
Post-back up thinning complete: 1 expired backups removed
5/31/12 7:32:42 AM
com.apple.backupd[5425]
Backup completed successfully.
5/31/12 7:53:33 AM
com.apple.backupd[5706]
Starting standard backup
5/31/12 7:53:33 AM
com.apple.backupd[5706]
Backing up to: /Volumes/Time Machine Backups/Backups.backupdb
5/31/12 7:55:13 AM
com.apple.backupd[5706]
Node requires deep traversal:/ reason:contains changes|must scan subdirs|fsevent|
5/31/12 7:57:26 AM
_spotlight[5735]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 7:57:26 AM
_spotlight[5740]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 7:57:35 AM
com.apple.backupd[5706]
No pre-backup thinning needed: 1.87 GB requested (including padding), 1.36 TB available
5/31/12 7:57:37 AM
_spotlight[5745]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 7:57:37 AM
_spotlight[5750]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 7:57:37 AM
_spotlight[5755]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 7:57:37 AM
_spotlight[5760]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 7:57:48 AM
com.apple.backupd[5706]
Copied 2085 files (18.9 MB) from volume Snow Leopard.
5/31/12 7:59:15 AM
com.apple.backupd[5706]
Node requires deep traversal:/ reason:contains changes|must scan subdirs|fsevent|
5/31/12 8:01:24 AM
_spotlight[5778]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 8:01:24 AM
_spotlight[5783]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 8:01:32 AM
com.apple.backupd[5706]
No pre-backup thinning needed: 1.87 GB requested (including padding), 1.36 TB available
5/31/12 8:01:34 AM
_spotlight[5788]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 8:01:34 AM
_spotlight[5793]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 8:01:34 AM
_spotlight[5798]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 8:01:34 AM
_spotlight[5803]
vol.notice /usr/fl/etc/volume.cfg not found
5/31/12 8:01:43 AM
com.apple.backupd[5706]
Copied 2085 files (18.9 MB) from volume Snow Leopard.
5/31/12 8:03:15 AM
com.apple.backupd[5706]
Starting post-backup thinning
5/31/12 8:31:33 AM
com.apple.backupd[5706]
Deleted backup /Volumes/Time Machine Backups/Backups.backupdb/warp/2012-05-29-123915: 1.36 TB now available
5/31/12 8:31:33 AM
com.apple.backupd[5706]
Post-back up thinning complete: 1 expired backups removed
5/31/12 8:31:35 AM
com.apple.backupd[5706]
Backup completed successfully.


The long version: in March I got a 2TB USB external drive to back up the Mac Pro at my work. I've had good success with Time Machine at home, backing up two MacBooks and a Mac Mini with no problems what-so-ever (it really "just works"), so I was surprised when Time Machine struggled with the Mac Pro. I did a lot of research on the web, and followed as much advice as I could (excluding the backup drive from Spotlight, making sure backups completed uninterrupted, repairing drives, etc.), but I continue to have backups that take almost a full hour (and thus Time Machine is almost constantly running). The actual quantity of data backed up is pretty small (similar to the log above, it's usually 20-50 MB).


During my initial backup I ran into a problem with Git which may be pertitent, but I'm not sure. I was in charge of my company's conversion from Subversion to Git, and in the process I locally cloned a bunch of Git repositories (on the order of 300-400 repos). Since Git uses hard links between local clones, this all fit on my 500GB drive no problem. But Time Machine attempted to back up each directory independently, and the backup ballooned to over 2.5TB and didn't fit on the 2TB external drive. After I excluded the Git repos from the backup, the intial backup completed without any visible problems.


Otherwise the machine is used for pretty standard software development (python, gcc, gdb, git), plus standard office email/web browsing.


I've been digging into this on and off since I first noticed the problem shortly after getting the drive and I'm running out of ideas. Anyone have additional suggestions on how to avoid the deep traversal? Let me know if there's additional useful information I've left out.


Thanks!

Stephen

Mac Pro (Mid 2010), Mac OS X (10.6.8)

Posted on May 31, 2012 6:23 AM

Reply
Question marked as Top-ranking reply

Posted on May 31, 2012 6:03 PM

Stephen Bash wrote:

. . .

5/31/12 6:55:00 AM com.apple.backupd[5425] Node requires deep traversal:/ reason:contains changes|must scan subdirs|fsevent|
...
5/31/12 6:59:02 AM com.apple.backupd[5425] Node requires deep traversal:/ reason:contains changes|must scan subdirs|fsevent|

That's curious. I've rarely seen those reason codes; it looks like there was a very large volume of file system changes, but that usually sends a different message.


Even stranger, it's doing a deep traversal on both passes of the same backup. I don't recall seeing that before, either, so I suspect a problem with the File System Event Store, the hidden log of file changes that OSX keps on each volume. TM normally uses it to see what needs to be backed-up, instead of the deep traversal.


Are you running any apps that routinely make many, many changes to the file system (files or folders added, changed, moved, renamed, etc.)?


5/31/12 7:01:05 AM _spotlight[5503] vol.notice /usr/fl/etc/volume.cfg not found

Spotlight is also very unhappy about whatever that is. I don't have an fl folder in my /usr folder, so have no idea what it is, or whether that's connected to the Time Machine problem, but it sure looks suspicious.


Any idea what it is?

174 replies

Feb 7, 2013 9:30 AM in response to Pondini

Podini,


I discovered something today. My problem seems to be with the File System Event Database as described in your FAQ.


Today I did the following.


Action 1) Created an empty folder.

Result 1) TM will backup that folder once, then use hard links on subsequent backups.


Action 2) Opened text edit and saved a small text file in the empty folder.

Result 2) TM will now create a new instance of the folder on each backup. The text file is backed up correctly via hard links on subsequent backups.


If I clear /.fseventsd, TM will behave correctly, but if I modify the text file with the empty folder, TM will backup the folder (and scan what is in it) every time.


This could definitely slow down TM as will have to scan numerous folders for no reason.


Can you try the above 2 actions on your system and let me know if you have the same behavior?


B

Feb 7, 2013 11:54 AM in response to bernuli

bernuli wrote:

. .

If I clear /.fseventsd, TM will behave correctly, but if I modify the text file with the empty folder, TM will backup the folder (and scan what is in it) every time.

If you mean it looks at the folder every time the file is changed, then yes, that's exactly how it's supposed to work.


The Event Store doesn't track each file that's added/changed/deleted; only each folder that has anything inside it changed. So when a change is made to a file, the enclosing folder is also marked as changed, and an entry made in the Event Store. On the next backup, TM compares the folder contents to the contents of the most recent backup of that folder.


Unless there's a problem with the Event Store, or some process marking lots of things changed that aren't changed, it works quickly.

Feb 7, 2013 3:49 PM in response to Pondini

Pondini wrote:



The Event Store doesn't track each file that's added/changed/deleted; only each folder that has anything inside it changed. So when a change is made to a file, the enclosing folder is also marked as changed, and an entry made in the Event Store. On the next backup, TM compares the folder contents to the contents of the most recent backup of that folder.



Right. But what is happening to me, is TM is scanning the folder and files within it, even though neither files within the folder, nor the parent, nor the folder istelf has had any change.


This is of course slowing things way down.



B

Feb 7, 2013 3:55 PM in response to bernuli

Then either something is marking the folders as changed, or the Event Store is getting damaged.


Have you installed a fresh version of OSX? If not, I'd start there.


Then I'd check all 3rd-party apps and kernel extensions for anything that might be suspicious.


If so, you might be able to figure out what's happening via the fseventr app. It's not been updated in quite some time, but should work on SL and might provide a clue.

Feb 7, 2013 5:05 PM in response to bernuli

bernuli wrote:

. . .

I've been holding off on a clean install of 10.6.8

No, I'm not recommending a clean install. Just a fresh version of OSX. That won't distrub anything else, and if the problem is something damaged in your current installation, it will fix it relatively easily.


See Reinstalling OSX in the pink box of Installing the ''combo'' update and/or Reinstalling OSX.

Feb 9, 2013 12:26 PM in response to Pondini

Hello all,

I used to be active in this conversation but then gave up on trying to solve the problem. However, I have been following the conversation to see if any new ideas were worth trying.

I see that CrashPlan came up as a question a few comments back. I use CrashPlan to back up my Documents folder to my mom's iMac. I also use Dropbox and SugarSync to sync files across my work and personal computers. I also do a TimeMachine backup to an external drive with LOTS of exclusions (mainly worried about documents, photos, music, media, etc. not applications). I then have a SuperDuper clone to a different external hard drive. So I have lots of redundancy and protection of loss. Perhaps one of these services or a combination of them could be part of the problem for these long backps of thousands of files when barely anything has actually been used or changed. Any thoughts?

RJ

Feb 9, 2013 4:10 PM in response to gogogut

RJ,


I don't use CrashPlan, SugarSync or dropbox and am affected by slow backups.


My system is pretty straight forward, only have installed, Office 2008, then 2011 and Adobe CS5 Design Premium, VMware, and Firefox.


I am pretty sure the TM slowdown occured sometime after I updated to 10.6.8.


RJ




gogogut wrote:


Hello all,

I used to be active in this conversation but then gave up on trying to solve the problem. However, I have been following the conversation to see if any new ideas were worth trying.

I see that CrashPlan came up as a question a few comments back. I use CrashPlan to back up my Documents folder to my mom's iMac. I also use Dropbox and SugarSync to sync files across my work and personal computers. I also do a TimeMachine backup to an external drive with LOTS of exclusions (mainly worried about documents, photos, music, media, etc. not applications). I then have a SuperDuper clone to a different external hard drive. So I have lots of redundancy and protection of loss. Perhaps one of these services or a combination of them could be part of the problem for these long backps of thousands of files when barely anything has actually been used or changed. Any thoughts?

RJ

Feb 12, 2013 12:54 AM in response to Tom Messenger

Well things seem to have stabilised for me - backups take about 7-8 minutes for the backup itself + another 7-8 minutes to remove expired backups from the external. (I have 50GB free on a 1TB volume.)


Overall it always copies 50,000 or so files (at least it thinks it is) amassing just a few MB if nothing changed, or ~500MB if my Dropbox or Sente database files were modified.


I can live with it, but it is too slow to run every hour in my opinion - the external is too noisy. It also eats too much space too quickly on the exernal due to the dropbox folder changes. If I let it backup every hour, I'd use up about 6-10GB per working day, which is just too much in my opinion. I've set it to backup once at night and every 4 hours when the system is awake. (Totalling about 2GB of writes per day.)


However - perhaps my system is now behaving completely normally? Are these times reasonable for a (full) FW800 drive? I have no deep traversals since deleting .fseventsd - so that seems to be a permanet fix for me (at least for now.)


Thanks Bernuli/Pondini et. al. And good luck to those chasing down the bug.

Feb 12, 2013 8:04 AM in response to ZX48

No, that's definitely not normal. 😟


As comparison, when I back up to a F/W 800 drive without having done anything significant, TM backs up perhaps 25-50 MB, says about 2500 files, deletes one expired backup, and takes 40-50 seconds from start to finish. That's on Mountain Lion, which is usually somewhat quicker than Snow Leopard, but not dramatically.


Something is wrong. You've apparently tried most of the known fixes. But if you haven't yet, try deleting the Spotlight index on your TM drive, per the pink box in #D2 of Time Machine - Troubleshooting. On the next backup, Time Machine will re-create it and re-index the backups, so that one will take quite a while, but thereafter backups may be very quick, but shouldn't be worse than now.


If still no help, try any of the other things in the green box that you haven't tried already.

Feb 12, 2013 8:19 AM in response to Pondini

My external HD (for TM) is currently excluded from Spotlight (as are three other partitions).


However, I've just had a poke in there (after enabling hidden files in TinkerTool).


Shoudl I delete the .Spotlight-V100 folder anyway? (It's only 29 KB)


Also, I have a folder named .fseventsd at the root of the external HD.


It is 8.6MB in size and contains 1013 items dating back to 15th Jan 2013. Is that normal?

Feb 12, 2013 8:32 AM in response to ZX48

ZX48 wrote:


My external HD (for TM) is currently excluded from Spotlight

Not really. 😉 Time Machine backups are indexed automatically. That setting applies only to other data on the disk. Effective with Lion, when you exclude a TM drive/partition, it puts up a message to that effect. See the paragraph that starts "Note. . ." in Mac OS X 10.6: Setting Spotlight preferences


Shoudl I delete the .Spotlight-V100 folder anyway? (It's only 29 KB)

Worth a try.


Also, I have a folder named .fseventsd at the root of the external HD.


It is 8.6MB in size and contains 1013 items dating back to 15th Jan 2013. Is that normal?

Yes, that's the File System Event Log for that volume. OSX keeps one on each HFS+ drive/partition.

Time Machine always requires deep traversal?

Welcome to Apple Support Community
A forum where Apple customers help each other with their products. Get started with your Apple Account.