Time Machine always does full backup

Question

gui-93 Author

Level 1

4 points

Time Machine always does full backup

Hello,

Time Machine always performs a full backup.

I even tried reinstalling Mac OS X from the recovery partition, but the problem persists.

Here's the logs:

Apr 28 00:01:41 iMac com.apple.backupd[2075]: Starting manual backup

Apr 28 00:01:41 iMac com.apple.backupd[2075]: Backing up to /dev/disk0s2: /Volumes/Archivio/Backups.backupdb

Apr 28 00:01:42 iMac com.apple.backupd[2075]: Forcing deep traversal on source: "HD" (device: /dev/disk1s2 mount: '/' fsUUID: 1538692E-2AEE-3458-8888-6EC3065EE2F1 eventDBUUID: E46EE01F-4056-452E-AA80-EBD3DD056084)

Apr 28 00:01:45 iMac com.apple.backupd[2075]: Total content size: 136.97 GB excluded items size: 9.4 GB for volume HD

Apr 28 00:01:45 iMac com.apple.backupd[2075]: Found 2389490 files (127.57 GB) needing backup

Apr 28 00:01:45 iMac com.apple.backupd[2075]: 149.04 GB required (including padding), 875.49 GB available

Apr 28 00:01:45 iMac com.apple.backupd[2075]: Waiting for index to be ready (100)

Apr 28 00:19:36 iMac kernel[0]: process backupd[2075] thread 354793 caught burning CPU! It used more than 50% CPU (Actual recent usage: 54%) over 180 seconds. thread lifetime cpu usage 264.058027 seconds, (151.269108 user, 112.788919 system) ledger info: balance: 90002892869 credit: 261954647441 debit: 171951754572 limit: 90000000000 (50%) period: 180000000000 time since last refill (ns): 164769182301

Apr 28 00:19:42 iMac spindump[508]: Saved cpu_resource.diag report for backupd version ??? (???) to /Library/Logs/DiagnosticReports/backupd_2016-04-28-001942_iMac.cpu_resource.dia g

Apr 28 00:59:54 iMac.lan backupd[2075]: nil error: Error Domain=NSCocoaErrorDomain Code=4099 "The connection to service named com.apple.bird was invalidated." UserInfo={NSDebugDescription=The connection to service named com.apple.bird was invalidated.}

Apr 28 01:01:37 iMac com.apple.backupd-helper[2077]: Not starting scheduled Time Machine backup: Backup already running

Apr 28 01:01:42 iMac com.apple.backupd[2075]: Copied 109.77 GB of 127.57 GB, 2175869 of 2389490 items

Apr 28 01:07:34 iMac com.apple.backupd[2075]: Copied 2321425 items (118.42 GB) from volume HD. Linked 0.

Apr 28 01:07:35 iMac com.apple.backupd[2075]: Created new backup: 2016-04-28-010735

Apr 28 01:07:36 iMac com.apple.backupd[2075]: Copying OS X Recovery set

Apr 28 01:07:51 iMac com.apple.backupd[2075]: Starting post-backup thinning

Apr 28 01:07:51 iMac com.apple.backupd[2075]: No post-backup thinning needed: no expired backups exist

Apr 28 01:07:51 iMac com.apple.backupd[2075]: Backup completed successfully.

Apr 28 02:02:07 iMac com.apple.backupd[2645]: Starting automatic backup

Apr 28 02:02:07 iMac com.apple.backupd[2645]: Backing up to /dev/disk0s2: /Volumes/Archivio/Backups.backupdb

Apr 28 02:02:09 iMac com.apple.backupd[2645]: Forcing deep traversal on source: "HD" (device: /dev/disk1s2 mount: '/' fsUUID: 1538692E-2AEE-3458-8888-6EC3065EE2F1 eventDBUUID: E46EE01F-4056-452E-AA80-EBD3DD056084)

Apr 28 02:02:09 iMac com.apple.backupd[2645]: Deep event scan at path:/ reason:must scan subdirs|require scan|

Apr 28 02:02:09 iMac com.apple.backupd[2645]: Running event scan

Apr 28 02:02:09 iMac com.apple.backupd[2645]: Finished scan

Apr 28 02:02:09 iMac com.apple.backupd[2645]: Saved event cache at /Volumes/Archivio/Backups.backupdb/iMac/2016-04-28-020209.inProgress/03FD950A-1 F83-4315-B404-79C5EA60B1C0/.1538692E-2AEE-3458-8888-6EC3065EE2F1.eventdb

Apr 28 02:02:09 iMac com.apple.backupd[2645]: Not using file event preflight for HD

Apr 28 02:07:31 iMac.lan backupd[2645]: nil error: Error Domain=NSCocoaErrorDomain Code=4099 "The connection to service named com.apple.bird was invalidated." UserInfo={NSDebugDescription=The connection to service named com.apple.bird was invalidated.}

Apr 28 02:07:43 iMac com.apple.backupd[2645]: Will copy (128.78 GB) from HD

Apr 28 02:07:43 iMac com.apple.backupd[2645]: Found 2319156 files (128.78 GB) needing backup

Apr 28 02:07:43 iMac com.apple.backupd[2645]: 162.04 GB required (including padding), 751.13 GB available

Apr 28 03:02:12 iMac com.apple.backupd[2645]: Copied 5.29 GB of 128.78 GB, 7207 of 2319156 items

Apr 28 04:02:13 iMac com.apple.backupd[2645]: Copied 29.14 GB of 128.78 GB, 801888 of 2319156 items

Apr 28 04:02:46 iMac kernel[0]: process backupd[2645] thread 400712 caught burning CPU! It used more than 50% CPU (Actual recent usage: 50%) over 180 seconds. thread lifetime cpu usage 312.982317 seconds, (170.161237 user, 142.821080 system) ledger info: balance: 90000677450 credit: 311062423130 debit: 221061745680 limit: 90000000000 (50%) period: 180000000000 time since last refill (ns): 177216305691

Apr 28 04:02:50 iMac spindump[508]: Saved cpu_resource.diag report for backupd version ??? (???) to /Library/Logs/DiagnosticReports/backupd_2016-04-28-040250_iMac.cpu_resource.dia g

Apr 28 04:41:45 iMac.lan backupd[2645]: nil error: Error Domain=NSCocoaErrorDomain Code=4099 "The connection to service named com.apple.bird was invalidated." UserInfo={NSDebugDescription=The connection to service named com.apple.bird was invalidated.}

Apr 28 04:50:37 iMac com.apple.backupd[2645]: Copied 2319723 items (118.33 GB) from volume HD. Linked 0.

Apr 28 04:50:37 iMac com.apple.backupd[2645]: Created new backup: 2016-04-28-045037

Apr 28 04:50:38 iMac com.apple.backupd[2645]: Starting post-backup thinning

Apr 28 04:50:38 iMac com.apple.backupd[2645]: No post-backup thinning needed: no expired backups exist

Apr 28 04:50:38 iMac com.apple.backupd[2645]: Backup completed successfully.

Apr 28 05:51:08 iMac com.apple.backupd[4661]: Starting automatic backup

Apr 28 05:51:08 iMac com.apple.backupd[4661]: Backing up to /dev/disk0s2: /Volumes/Archivio/Backups.backupdb

Apr 28 05:51:09 iMac com.apple.backupd[4661]: Forcing deep traversal on source: "HD" (device: /dev/disk1s2 mount: '/' fsUUID: 1538692E-2AEE-3458-8888-6EC3065EE2F1 eventDBUUID: E46EE01F-4056-452E-AA80-EBD3DD056084)

Apr 28 05:51:09 iMac com.apple.backupd[4661]: Deep event scan at path:/ reason:must scan subdirs|require scan|

Apr 28 05:51:09 iMac com.apple.backupd[4661]: Running event scan

Apr 28 05:51:09 iMac com.apple.backupd[4661]: Finished scan

Apr 28 05:51:09 iMac com.apple.backupd[4661]: Saved event cache at /Volumes/Archivio/Backups.backupdb/iMac/2016-04-28-055109.inProgress/268875A2-0 0DC-46ED-8A3B-36E28F4A6C74/.1538692E-2AEE-3458-8888-6EC3065EE2F1.eventdb

Apr 28 05:51:09 iMac com.apple.backupd[4661]: Not using file event preflight for HD

Apr 28 05:55:26 iMac.lan backupd[4661]: nil error: Error Domain=NSCocoaErrorDomain Code=4099 "The connection to service named com.apple.bird was invalidated." UserInfo={NSDebugDescription=The connection to service named com.apple.bird was invalidated.}

Apr 28 05:55:37 iMac com.apple.backupd[4661]: Will copy (128.77 GB) from HD

Apr 28 05:55:37 iMac com.apple.backupd[4661]: Found 2319936 files (128.77 GB) needing backup

Apr 28 05:55:38 iMac com.apple.backupd[4661]: 165.05 GB required (including padding), 625.52 GB available

Apr 28 06:13:24 iMac kernel[0]: process backupd[4661] thread 480660 caught burning CPU! It used more than 50% CPU (Actual recent usage: 54%) over 180 seconds. thread lifetime cpu usage 353.199622 seconds, (194.969995 user, 158.229627 system) ledger info: balance: 90000590481 credit: 350311678346 debit: 260311087865 limit: 90000000000 (50%) period: 180000000000 time since last refill (ns): 163799979806

Apr 28 06:13:28 iMac spindump[508]: Saved cpu_resource.diag report for backupd version ??? (???) to /Library/Logs/DiagnosticReports/backupd_2016-04-28-061328_iMac.cpu_resource.dia g

Apr 28 06:51:10 iMac com.apple.backupd[4661]: Copied 102.69 GB of 128.77 GB, 2146758 of 2319936 items

Apr 28 06:53:08 iMac.lan backupd[4661]: nil error: Error Domain=NSCocoaErrorDomain Code=4099 "The connection to service named com.apple.bird was invalidated." UserInfo={NSDebugDescription=The connection to service named com.apple.bird was invalidated.}

Apr 28 07:01:48 iMac com.apple.backupd[4661]: Copied 2319941 items (118.33 GB) from volume HD. Linked 0.

Apr 28 07:01:49 iMac com.apple.backupd[4661]: Created new backup: 2016-04-28-070148

Apr 28 07:01:50 iMac com.apple.backupd[4661]: Starting post-backup thinning

Apr 28 07:01:50 iMac com.apple.backupd[4661]: No post-backup thinning needed: no expired backups exist

Apr 28 07:01:50 iMac com.apple.backupd[4661]: Backup completed successfully.

Apr 28 08:02:20 iMac com.apple.backupd[5112]: Starting automatic backup

Apr 28 08:02:21 iMac com.apple.backupd[5112]: Backing up to /dev/disk0s2: /Volumes/Archivio/Backups.backupdb

Apr 28 08:02:22 iMac com.apple.backupd[5112]: Forcing deep traversal on source: "HD" (device: /dev/disk1s2 mount: '/' fsUUID: 1538692E-2AEE-3458-8888-6EC3065EE2F1 eventDBUUID: E46EE01F-4056-452E-AA80-EBD3DD056084)

Apr 28 08:02:22 iMac com.apple.backupd[5112]: Deep event scan at path:/ reason:must scan subdirs|require scan|

Apr 28 08:02:22 iMac com.apple.backupd[5112]: Running event scan

Apr 28 08:02:22 iMac com.apple.backupd[5112]: Finished scan

Apr 28 08:02:22 iMac com.apple.backupd[5112]: Saved event cache at /Volumes/Archivio/Backups.backupdb/iMac/2016-04-28-080222.inProgress/2FCA228A-D 4F1-4EDE-9192-72CF1B3193BC/.1538692E-2AEE-3458-8888-6EC3065EE2F1.eventdb

Apr 28 08:02:22 iMac com.apple.backupd[5112]: Not using file event preflight for HD

Apr 28 08:06:17 iMac.lan backupd[5112]: nil error: Error Domain=NSCocoaErrorDomain Code=4099 "The connection to service named com.apple.bird was invalidated." UserInfo={NSDebugDescription=The connection to service named com.apple.bird was invalidated.}

Apr 28 08:06:28 iMac com.apple.backupd[5112]: Will copy (128.78 GB) from HD

Apr 28 08:06:28 iMac com.apple.backupd[5112]: Found 2319946 files (128.78 GB) needing backup

Apr 28 08:06:29 iMac com.apple.backupd[5112]: 167.95 GB required (including padding), 503.35 GB available

Apr 28 08:21:09 iMac kernel[0]: process backupd[5112] thread 514599 caught burning CPU! It used more than 50% CPU (Actual recent usage: 57%) over 180 seconds. thread lifetime cpu usage 315.214919 seconds, (174.659268 user, 140.555651 system) ledger info: balance: 90002804135 credit: 312721050787 debit: 222718246652 limit: 90000000000 (50%) period: 180000000000 time since last refill (ns): 156851655644

Apr 28 08:21:12 iMac spindump[508]: Saved cpu_resource.diag report for backupd version ??? (???) to /Library/Logs/DiagnosticReports/backupd_2016-04-28-082112_iMac.cpu_resource.dia g

Apr 28 09:02:22 iMac com.apple.backupd[5112]: Copied 98.44 GB of 128.78 GB, 2128720 of 2319946 items

Apr 28 09:06:40 iMac.lan backupd[5112]: nil error: Error Domain=NSCocoaErrorDomain Code=4099 "The connection to service named com.apple.bird was invalidated." UserInfo={NSDebugDescription=The connection to service named com.apple.bird was invalidated.}

Apr 28 09:15:48 iMac com.apple.backupd[5112]: Copied 2319940 items (118.33 GB) from volume HD. Linked 0.

Apr 28 09:15:50 iMac com.apple.backupd[5112]: Created new backup: 2016-04-28-091549

Apr 28 09:15:51 iMac com.apple.backupd[5112]: Starting post-backup thinning

Apr 28 09:15:51 iMac com.apple.backupd[5112]: No post-backup thinning needed: no expired backups exist

Apr 28 09:15:51 iMac com.apple.backupd[5112]: Backup completed successfully.

Posted on Apr 28, 2016 5:57 AM

Reply

Answer 1

SeaPapp

Level 7

26,486 points

Apr 28, 2016 6:25 AM in response to gui-93

See if Pondini can help http://pondini.org/TM/D3.html

Reply

Answer 2

gui-93 Author

Level 1

4 points

Apr 28, 2016 10:03 AM in response to SeaPapp

Unfortunately I tried everything, but the issue still persists.

Reply

Answer 3

Linc Davis

Level 10

209,541 points

Apr 28, 2016 10:12 AM in response to gui-93

There may be a problem with the source drive or the backup drive.

I suggest that you make another full backup to a locally-attached external hard drive. You should do that anyway. One backup isn't enough to be safe.

Reply

Answer 4

gui-93 Author

Level 1

4 points

Apr 28, 2016 10:34 AM in response to Linc Davis

Hello,

I'm already backing up to 2 different drives. The problem is that it started doing full backups on both, so I assume the problem is with the source drive.

I tried reinstalling OS X, used Disk Utility's "First Aid", but the issue persists.

Do you have any idea? My last resort would be to restore from Time Machine, but I doubt it would work.

Thank you

Reply

Answer 5

Linc Davis

Level 10

209,541 points

Apr 28, 2016 11:11 AM in response to gui-93

Are you using FileVault?

Reply

Answer 6

gui-93 Author

Level 1

4 points

Apr 28, 2016 11:35 AM in response to Linc Davis

no

Reply

Answer 7

Linc Davis

Level 10

209,541 points

Apr 28, 2016 12:03 PM in response to gui-93

Please back up all data before making any changes. If you can't back up with Time Machine, use something else, or ask for instructions.

Triple-click anywhere in the line below on this page to select it:

/.fseventsd

Right-click or control-click the highlighted line and select

Services ▹ Open

from the contextual menu. A Finder window should open with an item named ".fseventsd" selected. Drag the selected item to the Trash. If the item is in the title bar of the Finder window, drag it from there to the Trash. You may be prompted for your administrator login password. Restart the computer and empty the Trash.

Reply

Answer 8

gui-93 Author

Level 1

4 points

Apr 28, 2016 3:25 PM in response to Linc Davis

Didn't work.

I also tried to restore from Time Machine, but it keeps doing full backups

Reply

Answer 9

gui-93 Author

Level 1

4 points

Apr 28, 2016 3:35 PM in response to gui-93

I think I may have just realised how the problem started.

A couple of days ago I used Carbon Copy Cloner to copy the internal HD to an SSD.

Since then I boot from the SSD (connected using thunderbolt), trim is enabled, do you think this might be the reason?

Reply

Answer 10

SeaPapp

Level 7

26,486 points

Apr 28, 2016 3:39 PM in response to gui-93

http://pondini.org/TM/27.html and http://pondini.org/TM/34.html

Reply

Answer 11

gui-93 Author

Level 1

4 points

Apr 28, 2016 3:42 PM in response to SeaPapp

Mike Sombrio wrote:

http://pondini.org/TM/27.html and http://pondini.org/TM/34.html

Thank you. I don't see how this applies to my situation.

Reply

Answer 12

Linc Davis

Level 10

209,541 points

Apr 28, 2016 3:44 PM in response to gui-93

do you think this might be the reason?

I don't know. Although it can be done, Apple doesn't recommend using TRIM with third-party SSD's. I suggest you disable it and see whether there's any change. I assume you know how to do that.

Reply

Answer 13

SeaPapp

Level 7

26,486 points

Apr 28, 2016 3:52 PM in response to gui-93

I'm suggesting that you may not have set up the disk rotation properly. No offense intended.

Reply

Answer 14

notcloudy

Level 4

1,252 points

Apr 28, 2016 4:01 PM in response to gui-93

There may be an issue with where time machine keeps its list of what has changed - your log shows it is doing a scan of all you files to see what has changed. Example of lost list - your system is frozen and you have to shutdown with the power button - time machine rescans the drive.

Reply

Answer 15

gui-93 Author

Level 1

4 points

Apr 28, 2016 5:17 PM in response to Linc Davis

Linc Davis wrote:

do you think this might be the reason?

I don't know. Although it can be done, Apple doesn't recommend using TRIM with third-party SSD's. I suggest you disable it and see whether there's any change. I assume you know how to do that.

Disabled TRIM, still full backups every time.

Reply