Time Machine taking a very long time for “Preparing backup…” and causing Finder to use 100% of one CPU core
Posted by jpluimers on 2021/04/26
Just in case I get Finder at 100% of one CPU core again while Time Machine is performing “Preparing backup…” for > 6 hours, I might take another look at these links:
- “time machine” “preparing backup” “finder” “cpu usage” – Google Search
- [WayBack] Time machine is stuck on preparing backup… – Apple Community
- [WayBack] High Sierra – Time Machine on “Prepar… – Apple Community
- [WayBack] Time machine stuck on preparing for back … – Apple Community
- [WayBack] How far is my Time Machine “preparing backup” step? – Ask Different
- On removing the “in progress file” (and sometimes rebooting):
This did not reveal much interesting back then:
clear; printf '\e[3J' && log show --predicate 'subsystem == "com.apple.TimeMachine"' --info --last 24h | grep -F 'eMac' | grep -Fv 'etat' | awk -F']' '{print substr($0,1,19), $NF}'
I was at Mac OS 10.3 High Sierra back then with 4 TB external backup disks backing up from the internal 1TB PCIe NVMe.
–jeroen
2019-05-03 10:03:18 Starting automatic backup 2019-05-03 10:03:18 Destination 2TBSlimBUP could not be found (url: (null) destinationID: 402658B7-EBD7-495A-ADA7-59C9252BAE0B) 2019-05-03 10:03:19 Backup failed with error 18: The backup disk could not be found. 2019-05-03 10:03:19 Starting automatic backup 2019-05-03 10:03:19 Destination 4TBMyPassportJWP could not be found (url: (null) destinationID: 2126483F-7989-467B-9E38-82C4F4515BBA) 2019-05-03 10:03:19 Backup failed with error 18: The backup disk could not be found. 2019-05-03 10:03:19 Starting automatic backup 2019-05-03 10:03:19 Backing up to /dev/disk5: /Volumes/4TBSeagateExpansionDriveJWP/Backups.backupdb 2019-05-03 10:03:24 Failed to remove attribute 'com.apple.backupd.SnapshotVolumeFSEventStoreUUID' from 'file:///', error: Error Domain=NSPOSIXErrorDomain Code=1 "Operation not permitted" 2019-05-03 10:03:24 Failed to remove attribute 'com.apple.backupd.SnapshotVolumeLastFSEventID' from 'file:///', error: Error Domain=NSPOSIXErrorDomain Code=1 "Operation not permitted" 2019-05-03 10:03:24 Failed to remove attribute 'com.apple.backupd.SnapshotVolumeUUID' from 'file:///', error: Error Domain=NSPOSIXErrorDomain Code=1 "Operation not permitted" 2019-05-03 10:03:24 Failed to remove attribute 'com.apple.backupd.PreviousSnapshotVolumeUUID' from 'file:///', error: Error Domain=NSPOSIXErrorDomain Code=1 "Operation not permitted" 2019-05-03 10:03:24 Failed to remove attribute 'com.apple.backupd.PreviousSnapshotVolumeName' from 'file:///', error: Error Domain=NSPOSIXErrorDomain Code=1 "Operation not permitted" 2019-05-03 10:03:24 Failed to remove attribute 'com.apple.backupd.VolumeBytesUsed' from 'file:///', error: Error Domain=NSPOSIXErrorDomain Code=1 "Operation not permitted" 2019-05-03 10:03:24 Failed to remove attribute 'com.apple.backupd.VolumeIsCaseSensitive' from 'file:///', error: Error Domain=NSPOSIXErrorDomain Code=1 "Operation not permitted" 2019-05-03 10:03:24 Failed to remove attribute 'com.apple.backupd.fstypename' from 'file:///', error: Error Domain=NSPOSIXErrorDomain Code=1 "Operation not permitted" 2019-05-03 10:03:24 Failed to remove attribute 'com.apple.backupd.VolumeIsEncrypted' from 'file:///', error: Error Domain=NSPOSIXErrorDomain Code=1 "Operation not permitted" 2019-05-03 10:03:24 Starting age based thinning of Time Machine local snapshots on disk '/' 2019-05-03 10:03:24 Skipping age based thinning of Time Machine reference snapshot 'com.apple.TimeMachine.2019-04-11-055115' on disk '/' 2019-05-03 10:03:24 Skipping age based thinning of Time Machine reference snapshot 'com.apple.TimeMachine.2019-04-11-070153' on disk '/' 2019-05-03 10:03:24 Age based thinning deleted Time Machine snapshot 'com.apple.TimeMachine.2019-05-02-093335' on disk '/ 2019-05-03 10:03:24 Age based thinning deleted Time Machine snapshot 'com.apple.TimeMachine.2019-05-02-172759' on disk '/ 2019-05-03 10:04:01 Completed thinning of snapshots on disk '/' in 36.4 seconds - current free space: 99.69 GB (99,693,531,136 bytes), target free space: 25 GB (25,000,000,000 bytes), initial free space: 100.5 GB (100,503,789,568 bytes), urgency: 0, remaining snapshots: ( "com.apple.Time "com.apple.TimeMachine.2019-04-11-055115", "com.apple.Time "com.apple.TimeMachine.2019-04-11-070153", "com.apple.Time "com.apple.TimeMachine.2019-05-02-102904", "com.apple.Time "com.apple.TimeMachine.2019-05-02-130954", "com.apple.Time "com.apple.TimeMachine.2019-05-02-151801", "com.apple.Time "com.apple.TimeMachine.2019-05-02-175834" 2019-05-03 10:04:01 Failed to write unlock record UUIDs as extended attribute for disk '/', error: Error Domain=NSPOSIXErrorDomain Code=1 "Operation not permitted" 2019-05-03 10:04:01 Created Time Machine local snapshot with name 'com.apple.TimeMachine.2019-05-03-100401' on disk '/' 2019-05-03 10:04:02 Declared stable snapshot: com.apple.TimeMachine.2019-05-03-100401 2019-05-03 10:04:02 Mounted stable snapshot: com.apple.TimeMachine.2019-05-03-100401 at path: /Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/RMBPro1TBJWP/2019-05-03-100401/Macintosh HD source: Macintosh HD 2019-05-03 10:04:02 Mounted reference snapshot: com.apple.TimeMachine.2019-04-11-070153 at path: /Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/RMBPro1TBJWP/2019-04-11-070153/Macintosh HD source: Macintosh HD 2019-05-03 10:04:04 Event store UUIDs don't match for volume: SamsungEvo512GB 2019-05-03 10:04:04 Event store UUIDs don't match for volume: Macintosh HD 2019-05-03 10:04:04 Deep event scan at path:/Volumes/SamsungEvo512GB reason:must scan subdirs|new event db| 2019-05-03 10:04:05 Running deep scan - looking for changes after 2019-04-11 05:01:53 +0000 2019-05-03 10:05:22 Finished scan 2019-05-03 10:05:22 Saved event cache at /Volumes/4TBSeagateExpansionDriveJWP/Backups.backupdb/RMBPro1TBJWP/2019-05-03-100403.inProgress/9AA8BCAC-21A0-4BF8-AF17-D06FAD2A3E23/.DE4B183B-A6E1-395E-B263-BF33344206AF.eventdb 2019-05-03 10:05:22 Not using file event preflight for /Volumes/SamsungEvo512GB 2019-05-03 10:05:43 Will copy (71.78 GB) from SamsungEvo512GB 2019-05-03 10:05:43 Deep event scan at path:/Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/RMBPro1TBJWP/2019-05-03-100401/Macintosh HD reason:must scan subdirs|new event db| 2019-05-03 10:05:43 Running deep scan - looking for changes after 2019-04-11 05:01:53 +0000 2019-05-03 16:30:20 Unmounted local snapshot: com.apple.TimeMachine.2019-05-03-100401 at path: /Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/RMBPro1TBJWP/2019-05-03-100401/Macintosh HD source: Macintosh HD 2019-05-03 16:30:20 Unmounted local snapshot: com.apple.TimeMachine.2019-04-11-070153 at path: /Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/RMBPro1TBJWP/2019-04-11-070153/Macintosh HD source: Macintosh HD 2019-05-03 16:30:20 Backup canceled.
The Wiert Corner - irregular stream of stuff 
Leave a comment