blog.hekt.org

Time Machine のバックアップ対象が延々と増え続けて焦った

タイトルの通り、Time Machine のバックアップ対象が延々と増え続けてバックアップが終わらなくなりました。

直前のバックアップでは 10 分程度で正常に完了しており、そのあとは Mac を放置してロッキーをみていたので、変更はほとんどないはずです。にもかかわらずバックアップ対象がどんどん増え続け、最初は数十 MB だったのが 10 GB を超えても終わらず、やむなくキャンセル。(このあと手動で再開させたら 20 GB を超えても終わらなかった)

明らかにおかしいので、いろいろ試してみました。

  1. Mac の再起動
  2. Disk Utility で TimeMachine ディスクをチェック
  3. TimeMachine にある .inProgress ファイルを削除
  4. Sophos Anti-Virus の Live Protection および On-access Scanning を停止
  5. Time Machine から前回のバックアップを削除

どうやらバックアップ削除が正着だったようで、このあと Time Machine を走らせると正常に終了しました。(もしかしたら .inProgress の削除も必要なことだったのかも)

おまけログ

途中でキャンセルしたやつ:

8/16/12 1:30:43.174 AM com.apple.backupd[7065]: Starting automatic backup
8/16/12 1:30:43.987 AM com.apple.backupd[7065]: Backing up to: /Volumes/TimeMachine/Backups.backupdb
8/16/12 1:30:45.358 AM com.apple.backupd[7065]: Using file event preflight for Macintosh HD
8/16/12 1:30:46.572 AM com.apple.backupd[7065]: Will copy (35 MB) from Macintosh HD
8/16/12 1:30:46.577 AM com.apple.backupd[7065]: Found 201 files (35 MB) needing backup
8/16/12 1:30:46.615 AM com.apple.backupd[7065]: 1.63 GB required (including padding), 85.07 GB available
8/16/12 1:39:06.763 AM com.apple.backupd[7065]: Copied 9498 files (9.16 GB) from volume Macintosh HD.
8/16/12 1:39:10.095 AM com.apple.backupd[7065]: Backup canceled.
8/16/12 1:49:38.504 AM com.apple.backupd[7269]: Starting manual backup
8/16/12 1:49:38.571 AM com.apple.backupd[7269]: Backing up to: /Volumes/TimeMachine/Backups.backupdb
8/16/12 1:49:39.888 AM com.apple.backupd[7269]: Using file event preflight for Macintosh HD
8/16/12 1:49:40.018 AM com.apple.backupd[7269]: Will copy (35.4 MB) from Macintosh HD
8/16/12 1:49:40.028 AM com.apple.backupd[7269]: Found 224 files (35.4 MB) needing backup
8/16/12 1:49:40.091 AM com.apple.backupd[7269]: 1.63 GB required (including padding), 84.26 GB available
8/16/12 2:16:10.776 AM com.apple.backupd[7269]: Copied 101310 files (20.92 GB) from volume Macintosh HD.
8/16/12 2:16:13.254 AM com.apple.backupd[7269]: Backup canceled.

直前の正常に完了したバックアップ:

8/16/12 12:30:44.061 AM com.apple.backupd[5889]: Starting automatic backup
8/16/12 12:30:44.079 AM com.apple.backupd[5889]: Backing up to: /Volumes/TimeMachine/Backups.backupdb
8/16/12 12:30:51.836 AM com.apple.backupd[5889]: Using file event preflight for Macintosh HD
8/16/12 12:30:51.884 AM com.apple.backupd[5889]: Will copy (31.9 MB) from Macintosh HD
8/16/12 12:30:51.887 AM com.apple.backupd[5889]: Found 129 files (31.9 MB) needing backup
8/16/12 12:30:51.926 AM com.apple.backupd[5889]: 1.69 GB required (including padding), 84.87 GB available
8/16/12 12:38:15.515 AM com.apple.backupd[5889]: Copied 3414 files (37.7 MB) from volume Macintosh HD.
8/16/12 12:38:16.100 AM com.apple.backupd[5889]: Using file event preflight for Macintosh HD
8/16/12 12:38:16.408 AM com.apple.backupd[5889]: Will copy (2.5 MB) from Macintosh HD
8/16/12 12:38:16.410 AM com.apple.backupd[5889]: Found 58 files (2.5 MB) needing backup
8/16/12 12:38:16.411 AM com.apple.backupd[5889]: 1.6 GB required (including padding), 84.87 GB available
8/16/12 12:38:37.563 AM com.apple.backupd[5889]: Copied 954 files (18.6 MB) from volume Macintosh HD.
8/16/12 12:38:39.855 AM com.apple.backupd[5889]: Created new backup: 2012-08-16-003838
8/16/12 12:38:43.738 AM com.apple.backupd[5889]: Starting post-backup thinning
8/16/12 12:40:19.850 AM com.apple.backupd[5889]: Deleted /Volumes/TimeMachine/Backups.backupdb/kaz の Mac mini/2012-07-17-111354 (206.4 MB)
8/16/12 12:40:19.850 AM com.apple.backupd[5889]: Post-back up thinning complete: 1 expired backups removed
8/16/12 12:40:20.120 AM com.apple.backupd[5889]: Backup completed successfully.

ようやくうまくいった:

8/16/12 3:05:58.208 AM com.apple.backupd[600]: Starting manual backup
8/16/12 3:05:58.764 AM com.apple.backupd[600]: Backing up to: /Volumes/TimeMachine/Backups.backupdb
8/16/12 3:06:03.196 AM com.apple.backupd[600]: Using file event preflight for Macintosh HD
8/16/12 3:06:28.594 AM com.apple.backupd[600]: Will copy (262.8 MB) from Macintosh HD
8/16/12 3:06:28.638 AM com.apple.backupd[600]: Found 1990 files (380.4 MB) needing backup
8/16/12 3:06:28.675 AM com.apple.backupd[600]: 1.88 GB required (including padding), 85.29 GB available
8/16/12 3:08:28.721 AM com.apple.backupd[600]: Copied 9803 files (424 MB) from volume Macintosh HD.
8/16/12 3:08:28.789 AM com.apple.backupd[600]: Using file event preflight for Macintosh HD
8/16/12 3:08:28.805 AM com.apple.backupd[600]: Will copy (208 KB) from Macintosh HD
8/16/12 3:08:28.807 AM com.apple.backupd[600]: Found 32 files (208 KB) needing backup
8/16/12 3:08:28.808 AM com.apple.backupd[600]: 1.45 GB required (including padding), 84.83 GB available
8/16/12 3:08:34.644 AM com.apple.backupd[600]: Copied 1261 files (1 MB) from volume Macintosh HD.
8/16/12 3:08:35.997 AM com.apple.backupd[600]: Created new backup: 2012-08-16-030834
8/16/12 3:08:38.127 AM com.apple.backupd[600]: Starting post-backup thinning
8/16/12 3:08:58.960 AM com.apple.backupd[600]: Deleted /Volumes/TimeMachine/Backups.backupdb/kaz の Mac mini/2012-08-15-012902 (39.4 MB)
8/16/12 3:08:58.960 AM com.apple.backupd[600]: Post-back up thinning complete: 1 expired backups removed
8/16/12 3:08:59.197 AM com.apple.backupd[600]: Backup completed successfully.