Last login: Fri May 24 18:34:28 on ttys000 donawalt@Bartholomew-M3-Max ~ % log show --last 1h --info --style compact --predicate 'subsystem=="com.apple.TimeMachine" ' Filtering the log data using "subsystem == "com.apple.TimeMachine"" Skipping debug messages, pass --debug to include. Timestamp Ty Process[PID:TID] 2024-05-24 18:08:56.151 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.powermanagement.systempowerstate 2024-05-24 18:08:56.151 I backupd-helper[1248:1918] [com.apple.TimeMachine:PowerManagement] TMPowerState: 0 2024-05-24 18:08:59.447 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.powermanagement.systempowerstate 2024-05-24 18:08:59.448 I backupd-helper[1248:1918] [com.apple.TimeMachine:PowerManagement] TMPowerState: 2 2024-05-24 18:09:21.050 E backupd[3019:16783] [com.apple.TimeMachine:General] com.apple.backupd.sandbox.xpc: connection invalid 2024-05-24 18:09:29.584 I backupd-helper[1248:163c5] [com.apple.TimeMachine:PowerManagement] TMPowerState: 0 2024-05-24 18:09:41.123 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.powermanagement.systempowerstate 2024-05-24 18:09:41.200 I backupd-helper[1248:1918] [com.apple.TimeMachine:PowerManagement] TMPowerState: 0 2024-05-24 18:15:09.522 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.powermanagement.systempowerstate 2024-05-24 18:15:09.523 I backupd-helper[1248:1918] [com.apple.TimeMachine:PowerManagement] TMPowerState: 0 2024-05-24 18:15:19.823 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.powermanagement.systempowerstate 2024-05-24 18:15:19.883 I backupd-helper[1248:1918] [com.apple.TimeMachine:PowerManagement] TMPowerState: 0 2024-05-24 18:19:45.645 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.powermanagement.systempowerstate 2024-05-24 18:19:45.647 I backupd-helper[1248:1918] [com.apple.TimeMachine:PowerManagement] TMPowerState: 0 2024-05-24 18:19:58.458 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.powermanagement.systempowerstate 2024-05-24 18:19:58.506 I backupd-helper[1248:1918] [com.apple.TimeMachine:PowerManagement] TMPowerState: 0 2024-05-24 18:25:25.897 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.powermanagement.systempowerstate 2024-05-24 18:25:25.898 I backupd-helper[1248:1918] [com.apple.TimeMachine:PowerManagement] TMPowerState: 0 2024-05-24 18:25:29.027 E backupd[3019:16bca] [com.apple.TimeMachine:General] com.apple.backupd.sandbox.xpc: connection invalid 2024-05-24 18:25:38.771 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.powermanagement.systempowerstate 2024-05-24 18:25:38.819 I backupd-helper[1248:1918] [com.apple.TimeMachine:PowerManagement] TMPowerState: 0 2024-05-24 18:28:31.466 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.powermanagement.systempowerstate 2024-05-24 18:28:31.467 I backupd-helper[1248:1918] [com.apple.TimeMachine:PowerManagement] TMPowerState: 0 2024-05-24 18:28:31.574 I backupd-helper[1248:163c5] [com.apple.TimeMachine:PowerManagement] TMPowerState: 0 2024-05-24 18:28:31.575 I backupd-helper[1248:1703b] [com.apple.TimeMachine:General] Skipping scheduled Time Machine backup: Autobackup interval is 1 hours but only 0.8 hours have elapsed since last auto backup attempt at 2024-05-24 21:40:45 +0000 2024-05-24 18:28:34.640 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.powermanagement.systempowerstate 2024-05-24 18:28:34.643 I backupd-helper[1248:1918] [com.apple.TimeMachine:PowerManagement] TMPowerState: 2 2024-05-24 18:28:59.267 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.powermanagement.systempowerstate 2024-05-24 18:28:59.351 I backupd-helper[1248:1918] [com.apple.TimeMachine:PowerManagement] TMPowerState: 0 2024-05-24 18:32:31.001 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.powermanagement.systempowerstate 2024-05-24 18:32:31.001 I backupd-helper[1248:1918] [com.apple.TimeMachine:PowerManagement] TMPowerState: 0 2024-05-24 18:32:31.442 E backupd[3019:17031] [com.apple.TimeMachine:General] com.apple.backupd.sandbox.xpc: connection invalid 2024-05-24 18:32:34.505 I backupd-helper[1248:1703b] [com.apple.TimeMachine:PowerManagement] TMPowerState: 0 2024-05-24 18:32:43.778 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.powermanagement.systempowerstate 2024-05-24 18:32:43.809 I backupd-helper[1248:1918] [com.apple.TimeMachine:PowerManagement] TMPowerState: 0 2024-05-24 18:33:37.348 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.powermanagement.systempowerstate 2024-05-24 18:33:37.385 I backupd-helper[1248:1918] [com.apple.TimeMachine:PowerManagement] TMPowerState: 2 2024-05-24 18:33:41.491 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.mobile.keybagd.lock_status 2024-05-24 18:33:41.500 I backupd-helper[1248:1918] [com.apple.TimeMachine:PowerManagement] TMPowerState: 2 2024-05-24 18:33:41.500 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Triggered for screen unlock! 2024-05-24 18:33:41.501 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.mobile.keybagd.lock_status 2024-05-24 18:33:41.501 I backupd-helper[1248:1918] [com.apple.TimeMachine:PowerManagement] TMPowerState: 2 2024-05-24 18:33:41.501 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Triggered for screen unlock! 2024-05-24 18:33:52.907 E backupd[3019:176e2] [com.apple.TimeMachine:General] com.apple.backupd.sandbox.xpc: connection invalid 2024-05-24 18:34:07.539 I backupd-helper[1248:163c5] [com.apple.TimeMachine:PowerManagement] TMPowerState: 2 2024-05-24 18:34:07.540 I backupd-helper[1248:163c5] [com.apple.TimeMachine:General] Failed to find a disk matching UUID 2253ECCA-353C-45AF-AFB3-7D8A16355427 2024-05-24 18:34:28.137 E backupd[3019:17a3c] [com.apple.TimeMachine:General] com.apple.backupd.sandbox.xpc: connection invalid 2024-05-24 18:36:41.439 E SystemUIServer[1742:185dd] [com.apple.TimeMachine:General] Failed to enumerate URLs under /Volumes/MacBook Pro SSD 2 for SnapshotStorage reuse with error Error Domain=NSCocoaErrorDomain Code=257 "The file “MacBook Pro SSD 2” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/MacBook%20Pro%20SSD%202, NSFilePath=/Volumes/MacBook Pro SSD 2, NSUnderlyingError=0x600000866880 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}} 2024-05-24 18:36:41.998 E SystemUIServer[1742:185dd] [com.apple.TimeMachine:General] Failed to enumerate URLs under /Volumes/MacBook Pro SSD 2 for SnapshotStorage reuse with error Error Domain=NSCocoaErrorDomain Code=257 "The file “MacBook Pro SSD 2” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/MacBook%20Pro%20SSD%202, NSFilePath=/Volumes/MacBook Pro SSD 2, NSUnderlyingError=0x60000084cea0 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}} 2024-05-24 18:37:03.068 E TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:AppleEventHandling] No pending apple event to handle 2024-05-24 18:37:03.596 E TimeMachineSettings[3507:18a47] [com.apple.TimeMachine:MountedVolumeMonitor] Failed to create volume info from disk ' '/System/Volumes/Data/home'', error: missingName 2024-05-24 18:37:03.620 E TimeMachineSettings[3507:18a47] [com.apple.TimeMachine:General] Failed to enumerate URLs under /Volumes/MacBook Pro SSD 2 for SnapshotStorage reuse with error Error Domain=NSCocoaErrorDomain Code=257 "The file “MacBook Pro SSD 2” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/MacBook%20Pro%20SSD%202, NSFilePath=/Volumes/MacBook Pro SSD 2, NSUnderlyingError=0x600000c54b70 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}} 2024-05-24 18:37:26.102 E TimeMachineSettings[3507:18a35] [com.apple.TimeMachine:MountedVolumeMonitor] Failed to create volume info from disk ' '/System/Volumes/Data/home'', error: missingName 2024-05-24 18:37:26.791 E TimeMachineSettings[3507:18ade] [com.apple.TimeMachine:MountedVolumeMonitor] Failed to create volume info from disk ' '/System/Volumes/Data/home'', error: missingName 2024-05-24 18:37:53.777 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] Adding new APFS volume with name: 'MacBook Pro SSD 2' 2024-05-24 18:37:53.801 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncStartedForDisk: {id = /dev/disk7} 2024-05-24 18:37:53.801 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: nil 2024-05-24 18:37:53.803 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncStartedForDisk: {id = /dev/disk7} 2024-05-24 18:37:53.803 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: nil 2024-05-24 18:37:53.803 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncMessageForDisk: {id = /dev/disk7} Preparing to add APFS Volume to APFS Container disk7 (null) 2024-05-24 18:37:53.803 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncProgressForDisk: {id = /dev/disk7} 5.000000 2024-05-24 18:37:53.803 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: Optional(0.05) 2024-05-24 18:37:53.832 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncProgressForDisk: {id = /dev/disk7} 25.000000 2024-05-24 18:37:53.832 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: Optional(0.25) 2024-05-24 18:37:53.833 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncMessageForDisk: {id = /dev/disk7} Creating APFS Volume (null) 2024-05-24 18:37:53.833 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncProgressForDisk: {id = /dev/disk7} 40.000000 2024-05-24 18:37:53.833 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: Optional(0.4) 2024-05-24 18:37:53.833 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncProgressForDisk: {id = /dev/disk7} 60.000000 2024-05-24 18:37:53.833 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: Optional(0.6) 2024-05-24 18:37:54.829 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncMessageForDisk: {id = /dev/disk7} Created new APFS Volume disk7s2 (null) 2024-05-24 18:37:54.829 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncProgressForDisk: {id = /dev/disk7} 75.000000 2024-05-24 18:37:54.829 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: Optional(0.75) 2024-05-24 18:37:54.829 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncProgressForDisk: {id = /dev/disk7} 80.000000 2024-05-24 18:37:54.829 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: Optional(0.8) 2024-05-24 18:37:54.829 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncMessageForDisk: {id = /dev/disk7} Mounting APFS Volume (null) 2024-05-24 18:37:54.913 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncProgressForDisk: {id = /dev/disk7} 90.000000 2024-05-24 18:37:54.913 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: Optional(0.9) 2024-05-24 18:37:54.925 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncFinishedForDisk: {id = /dev/disk7} { NewDiskBSD = disk7s2; NewDiskDADiskRef = "{id = /dev/disk7s2}"; } 2024-05-24 18:37:54.925 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncMessageForDisk: {id = /dev/disk7} Setting volume permissions (null) 2024-05-24 18:37:54.925 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncProgressForDisk: {id = /dev/disk7} 100.000000 2024-05-24 18:37:54.925 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: Optional(1) 2024-05-24 18:37:54.998 I backupd-helper[1248:1918] [com.apple.TimeMachine:General] Mountpoint '/Volumes/MacBook Pro SSD 2 1' is still valid 2024-05-24 18:37:55.048 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:General] Unmounted '/Volumes/MacBook Pro SSD 2' 2024-05-24 18:37:55.053 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] Deleting previous APFS volume '/Volumes/MacBook Pro SSD 2' 2024-05-24 18:37:55.074 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncStartedForDisk: {id = /dev/disk7s1} 2024-05-24 18:37:55.074 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: nil 2024-05-24 18:37:55.082 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncStartedForDisk: (null) 2024-05-24 18:37:55.082 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: nil 2024-05-24 18:37:55.082 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncMessageForDisk: (null) Deleting APFS Volume from its APFS Container (null) 2024-05-24 18:37:55.082 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncProgressForDisk: (null) 10.000000 2024-05-24 18:37:55.082 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: Optional(0.1) 2024-05-24 18:37:55.083 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncMessageForDisk: {id = /dev/disk7s1} Unmounting disk7s1 (null) 2024-05-24 18:37:55.083 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncProgressForDisk: {id = /dev/disk7s1} 20.000000 2024-05-24 18:37:55.084 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: Optional(0.2) 2024-05-24 18:37:55.085 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncMessageForDisk: {id = /dev/disk7s1} Erasing any xART session referenced by DC97C7EE-C27E-4AFD-99A1-81CF86C22106 (null) 2024-05-24 18:37:55.085 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncProgressForDisk: {id = /dev/disk7s1} 35.000000 2024-05-24 18:37:55.085 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: Optional(0.35) 2024-05-24 18:37:55.140 E TMHelperAgent[3541:18f2b] [com.apple.TimeMachine:General] com.apple.TMHelperAgent.DeliverNotification: connection invalid 2024-05-24 18:37:55.183 E mdsync[3542:18f32] [com.apple.TimeMachine:TMSession] Failed to create session for '/System/Volumes/Data/Volumes/MacBook Pro SSD 2 1' with options: 4, error: Error Domain=TMStructureErrorDomain Code=4 "APFSMachineStore - Structure missing" UserInfo={NSUnderlyingError=0x144908a80 {Error Domain=NSPOSIXErrorDomain Code=2 "No such file or directory"}, TMStructureStructureName=APFSMachineStore, NSLocalizedDescription=APFSMachineStore - Structure missing} 2024-05-24 18:37:55.190 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncMessageForDisk: {id = /dev/disk7s1} Deleting Volume (null) 2024-05-24 18:37:55.190 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncProgressForDisk: {id = /dev/disk7s1} 50.000000 2024-05-24 18:37:55.190 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: Optional(0.5) 2024-05-24 18:37:55.201 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncProgressForDisk: {id = /dev/disk7s1} 75.000000 2024-05-24 18:37:55.201 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: Optional(0.75) 2024-05-24 18:37:55.201 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncMessageForDisk: {id = /dev/disk7s1} Removing any Preboot and Recovery Directories (null) 2024-05-24 18:37:55.218 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncFinishedForDisk: (null) { } 2024-05-24 18:37:55.218 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:LocalPreparationController] dmAsyncProgressForDisk: {id = /dev/disk7s1} 100.000000 2024-05-24 18:37:55.218 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation progress message: 'Preparing “MacBook Pro SSD 2”…', fractionDone: Optional(1) 2024-05-24 18:37:55.225 I TimeMachineSettings[3507:18a32] [com.apple.TimeMachine:PreparationController] Reporting preparation finished 2024-05-24 18:37:55.466 E TimeMachineSettings[3507:18daf] [com.apple.TimeMachine:MountedVolumeMonitor] Failed to create volume info from disk ' '/System/Volumes/Data/home'', error: missingName 2024-05-24 18:37:56.089 E TimeMachineSettings[3507:18e36] [com.apple.TimeMachine:MountedVolumeMonitor] Failed to create volume info from disk ' '/System/Volumes/Data/home'', error: missingName 2024-05-24 18:38:03.830 I SystemUIServer[1742:22e2] [com.apple.TimeMachine:MenuExtra] User tried to start a backup 2024-05-24 18:38:03.832 I backupd[3019:18f95] [com.apple.TimeMachine:BackupDispatching] Backup requested to next destination in rotation. specifiedOptions: TMBackupOptions(rawValue: 0) 2024-05-24 18:38:03.877 I backupd[3019:18f95] [com.apple.TimeMachine:BackupScheduling] Not prioritizing backups with priority errors. lockState=0 2024-05-24 18:38:03.888 I backupd[3019:18f99] [com.apple.TimeMachine:BackupDispatching] Starting backup with mode "manual backup" 2024-05-24 18:38:03.908 I backupd[3019:18f95] [com.apple.TimeMachine:General] Mountpoint '/Volumes/MacBook Pro SSD 2 1' is still valid 2024-05-24 18:38:03.912 I backupd[3019:18f95] [com.apple.TimeMachine:General] Mountpoint '/Volumes/MacBook Pro SSD 2 1' is still valid 2024-05-24 18:38:04.363 I TimeMachineSettings[3507:18e56] [com.apple.TimeMachine:LogLimits] Limiting logging for limit: aFewTimes key: "map auto_home" 2024-05-24 18:38:05.070 I backupd[3019:18f9b] [com.apple.TimeMachine:General] Checking identity of target volume '/Volumes/MacBook Pro SSD 2 1' 2024-05-24 18:38:05.071 I backupd[3019:18f9b] [com.apple.TimeMachine:General] Mountpoint '/Volumes/MacBook Pro SSD 2 1' is still valid 2024-05-24 18:38:06.145 I backupd[3019:18f9b] [com.apple.TimeMachine:Quota] Set quota for '/Volumes/MacBook Pro SSD 2 1' to none 2024-05-24 18:38:07.252 I backupd[3019:18f9b] [com.apple.TimeMachine:General] Mountpoint '/Volumes/MacBook Pro SSD 2 1' is still valid 2024-05-24 18:38:07.262 E TMHelperAgent[3541:18fab] [com.apple.TimeMachine:General] com.apple.TMHelperAgent.DeliverNotification: connection invalid 2024-05-24 18:38:07.262 I backupd[3019:18f9b] [com.apple.TimeMachine:BackupEngine] Backing up 1 volumes to MacBook Pro SSD 2 (/dev/disk7s2,TMBackupOptions(rawValue: 0)): /Volumes/MacBook Pro SSD 2 1 2024-05-24 18:38:07.262 I backupd[3019:18f9b] [com.apple.TimeMachine:IOCheck] Checking destination IO performance at "/Volumes/MacBook Pro SSD 2 1" 2024-05-24 18:38:07.581 I backupd[3019:18f9b] [com.apple.TimeMachine:IOCheck] Wrote 1 50 MB file at 161.33 MB/s to "/Volumes/MacBook Pro SSD 2 1" in 0.310 seconds 2024-05-24 18:38:07.652 I backupd[3019:18f9b] [com.apple.TimeMachine:IOCheck] Concurrently wrote 500 4 KB files at 43.57 MB/s to "/Volumes/MacBook Pro SSD 2 1" in 0.047 seconds 2024-05-24 18:38:14.465 I backupd[3019:18f9b] [com.apple.TimeMachine:General] Mountpoint '/Volumes/MacBook Pro SSD 2 1' is still valid 2024-05-24 18:38:14.465 I backupd[3019:18f9b] [com.apple.TimeMachine:Spotlight] Running with Spotlight inline indexing disabled. 2024-05-24 18:38:15.523 E backupd[3019:18f9b] [com.apple.TimeMachine:General] fs_snapshot_list failed: Operation not supported 2024-05-24 18:38:15.570 E backupd[3019:18f9b] [com.apple.TimeMachine:General] fs_snapshot_list failed: Operation not supported 2024-05-24 18:38:15.580 I backupd[3019:18f9b] [com.apple.TimeMachine:LocalThinning] Starting age based thinning of Time Machine local snapshots on disk '/' 2024-05-24 18:38:15.580 I backupd[3019:19019] [com.apple.TimeMachine:LocalThinning] Starting age based thinning of Time Machine local snapshots on disk '/System/Volumes/Data' 2024-05-24 18:38:15.580 I backupd[3019:19018] [com.apple.TimeMachine:LocalThinning] Starting age based thinning of Time Machine local snapshots on disk '/Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/Bartholomew M3 Max/2024-05-24-151423/Macintosh HD - Data' 2024-05-24 18:38:15.589 E backupd[3019:19018] [com.apple.TimeMachine:General] Failed to delete APFS snapshot with name 'com.apple.TimeMachine.2024-05-23-162745.local' on volume '/Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/Bartholomew M3 Max/2024-05-24-151423/Macintosh HD - Data', error: Error Domain=NSPOSIXErrorDomain Code=6 "Device not configured" 2024-05-24 18:38:15.589 I backupd[3019:19019] [com.apple.TimeMachine:LocalThinning] Age-based thinning deleted Time Machine snapshot 'com.apple.TimeMachine.2024-05-23-162745.local' on disk '/System/Volumes/Data 2024-05-24 18:38:15.592 E backupd[3019:19018] [com.apple.TimeMachine:General] Failed to delete APFS snapshot with name 'com.apple.TimeMachine.2024-05-23-162745' on volume '/Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/Bartholomew M3 Max/2024-05-24-151423/Macintosh HD - Data', error: Error Domain=NSPOSIXErrorDomain Code=6 "Device not configured" 2024-05-24 18:38:15.595 E backupd[3019:19018] [com.apple.TimeMachine:General] Failed to delete APFS snapshot with name 'com.apple.TimeMachine.2024-05-23-173204.local' on volume '/Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/Bartholomew M3 Max/2024-05-24-151423/Macintosh HD - Data', error: Error Domain=NSPOSIXErrorDomain Code=6 "Device not configured" 2024-05-24 18:38:15.595 E backupd[3019:19018] [com.apple.TimeMachine:General] Failed to delete APFS snapshot with name 'com.apple.TimeMachine.2024-05-23-173204' on volume '/Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/Bartholomew M3 Max/2024-05-24-151423/Macintosh HD - Data', error: Error Domain=NSPOSIXErrorDomain Code=6 "Device not configured" 2024-05-24 18:38:15.597 E backupd[3019:19018] [com.apple.TimeMachine:General] Failed to delete APFS snapshot with name 'com.apple.TimeMachine.2024-05-24-151423.local' on volume '/Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/Bartholomew M3 Max/2024-05-24-151423/Macintosh HD - Data', error: Error Domain=NSPOSIXErrorDomain Code=6 "Device not configured" 2024-05-24 18:38:15.597 E backupd[3019:19018] [com.apple.TimeMachine:General] Failed to delete APFS snapshot with name 'com.apple.TimeMachine.2024-05-24-151423' on volume '/Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/Bartholomew M3 Max/2024-05-24-151423/Macintosh HD - Data', error: Error Domain=NSPOSIXErrorDomain Code=6 "Device not configured" 2024-05-24 18:38:15.598 I backupd[3019:19019] [com.apple.TimeMachine:LocalThinning] Age-based thinning deleted Time Machine snapshot 'com.apple.TimeMachine.2024-05-23-173204.local' on disk '/System/Volumes/Data 2024-05-24 18:38:15.804 I backupd[3019:19019] [com.apple.TimeMachine:General] Unmounted '/Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/Bartholomew M3 Max/2024-05-24-151423/Macintosh HD - Data' 2024-05-24 18:38:15.811 I backupd[3019:19019] [com.apple.TimeMachine:LocalThinning] Age-based thinning deleted Time Machine snapshot 'com.apple.TimeMachine.2024-05-24-151423.local' on disk '/System/Volumes/Data 2024-05-24 18:38:15.812 I backupd[3019:18f9b] [com.apple.TimeMachine:BackupThinning] No further thinning possible - no thinnable backups 2024-05-24 18:38:19.543 I backupd[3019:18f9b] [com.apple.TimeMachine:LocalSnapshotManagement] Created Time Machine local snapshot with name 'com.apple.TimeMachine.2024-05-24-183815.local' on disk '/System/Volumes/Data' 2024-05-24 18:38:19.544 I backupd[3019:18f9b] [com.apple.TimeMachine:General] Declared stable snapshot: com.apple.TimeMachine.2024-05-24-183815.local 2024-05-24 18:38:19.560 I backupd[3019:18f9b] [com.apple.TimeMachine:LocalSnapshotManagement] Mounted stable snapshot: com.apple.TimeMachine.2024-05-24-183815.local at path: /Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/Bartholomew M3 Max/2024-05-24-183815/Macintosh HD - Data source: Macintosh HD - Data 2024-05-24 18:38:19.561 I backupd[3019:18f9b] [com.apple.TimeMachine:FileProviderManager] Checking if file provider is ready for backup at path file:///Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/Bartholomew%20M3%20Max/2024-05-24-183815/Macintosh%20HD%20-%20Data/ 2024-05-24 18:38:19.563 I backupd[3019:18f9b] [com.apple.TimeMachine:FileProviderManager] File provider reported file:///Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/Bartholomew%20M3%20Max/2024-05-24-183815/Macintosh%20HD%20-%20Data/ is ready for backup 2024-05-24 18:38:21.246 I backupd[3019:18f9b] [com.apple.TimeMachine:EventCollection] Collecting events on 1 volumes... 2024-05-24 18:38:21.814 I backupd[3019:18f9b] [com.apple.TimeMachine:EventCollection] First backup of source: "Macintosh HD - Data" (device: /dev/disk3s5 mount: '/System/Volumes/Data' fsUUID: AC4EAFCE-5FC1-4D17-A94E-73CF759E91E9 eventDBUUID: 5BA407BE-4E30-400B-8E0B-28A96623B036) firstBackupOfVolume 2024-05-24 18:38:21.816 I backupd[3019:18f9b] [com.apple.TimeMachine:EventCollection] Doing secondary scan of nodes needing deep traversal (Macintosh HD - Data) 2024-05-24 18:38:21.816 I backupd[3019:18f9b] [com.apple.TimeMachine:EventCollection] Finished secondary scan of Macintosh HD - Data 2024-05-24 18:38:21.816 I backupd[3019:18f9b] [com.apple.TimeMachine:EventCollection] EventDatabase: (empty) strategy: First backup 2024-05-24 18:38:21.817 I backupd[3019:18f9b] [com.apple.TimeMachine:EventCollection] "Macintosh HD - Data" will need a full copy 2024-05-24 18:38:21.817 I backupd[3019:18f9b] [com.apple.TimeMachine:EventCollection] Finishing "Macintosh HD - Data": 0 (-) of 100% - 1 viewpoints Average Speed: 0.00 events/s 2024-05-24 18:38:21.843 I backupd[3019:18f9b] [com.apple.TimeMachine:EventCollection] Finished collecting events on 1 volumes... 2024-05-24 18:38:22.913 I backupd[3019:18f9b] [com.apple.TimeMachine:SizingProgress] Estimated a total of 1490116 files (1.03 TB) will be in backup of 'Macintosh HD - Data' 2024-05-24 18:38:22.913 I backupd[3019:18f9b] [com.apple.TimeMachine:SizingProgress] Estimated full backup will contain 1490116 files (1.03 TB) from all sources 2024-05-24 18:38:22.913 I backupd[3019:18f9b] [com.apple.TimeMachine:BackupCopier] Disabling dataless directory preservation. 2024-05-24 18:38:22.914 I backupd[3019:18f9b] [com.apple.TimeMachine:EventCollection] Skipping "Macintosh HD - Data" (device: /dev/disk3s5 mount: '/System/Volumes/Data' fsUUID: AC4EAFCE-5FC1-4D17-A94E-73CF759E91E9 eventDBUUID: 5BA407BE-4E30-400B-8E0B-28A96623B036) for tree top progress. (First backup) 2024-05-24 18:38:22.914 I backupd[3019:18f9b] [com.apple.TimeMachine:CopyProgress] Starting propagation look-ahead for "Macintosh HD - Data" 2024-05-24 18:38:22.914 I backupd[3019:18f9b] [com.apple.TimeMachine:OM] No optimized moves cache needed for first backup. 2024-05-24 18:38:22.914 I backupd[3019:18f9b] [com.apple.TimeMachine:CopyProgress] Copying from source ""Macintosh HD - Data" (device: /dev/disk3s5 mount: '/System/Volumes/Data' fsUUID: AC4EAFCE-5FC1-4D17-A94E-73CF759E91E9 eventDBUUID: 5BA407BE-4E30-400B-8E0B-28A96623B036)" to "/Volumes/MacBook Pro SSD 2 1/2024-05-24-183819.inprogress" 2024-05-24 18:38:22.915 I backupd[3019:18fd7] [com.apple.TimeMachine:LockState] Changed from "none" to "deviceIsUnlocked" 2024-05-24 18:38:29.103 I backupd[3019:18fda] [com.apple.TimeMachine:CopyProgress] Reached a consensus to calculate time remaining. Yeas: ByPhysicalSize,ByItemCount 2024-05-24 18:38:36.471 E backupd[3019:18fda] [com.apple.TimeMachine:General] Failed setting clone extents xattr: {length = 432, bytes = 0x00000000 00000000 0090e49b 5b000000 ... 93d57500 00000000 } for path: '/Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/Bartholomew M3 Max/2024-05-24-183815/Macintosh HD - Data/Library/Application Support/Microsoft/TeamsUpdaterDaemon/Pkgs/644532B9-ACB6-4954-90F9-DEBFB86CE449-60438-0000094590B0C787_Teams_osx.pkg', error: Error Domain=NSPOSIXErrorDomain Code=1 "Operation not permitted" UserInfo={NSFilePath=/Volumes/MacBook Pro SSD 2 1/2024-05-24-183819.inprogress/Macintosh HD - Data/Library/Application Support/Microsoft/TeamsUpdaterDaemon/Pkgs/644532B9-ACB6-4954-90F9-DEBFB86CE449-60438-0000094590B0C787_Teams_osx.pkg, NSLocalizedDescription=/Volumes/MacBook Pro SSD 2 1/2024-05-24-183819.inprogress/Macintosh HD - Data/Library/Application Support/Microsoft/TeamsUpdaterDaemon/Pkgs/644532B9-ACB6-4954-90F9-DEBFB86CE449-60438-0000094590B0C787_Teams_osx.pkg: Operation not permitted (error 1)} 2024-05-24 18:38:42.936 I backupd[3019:18fda] [com.apple.TimeMachine:CopyProgress] . . Progress: 1% done, -, - MB/s, avg: 0.00 MB/s, - items/s, avg: 0.00 items/s ByPhysicalSize:0% (CV:2.42 GB/951.21 GB (D: Zero KB),T:951.21 GB,D:Zero KB(-),LA:Zero KB(-))Y ByItemCount:1% (CV:16684/1459614 (D: 0),T:1459614,D:0(-),LA:0(-))Y ByEventPath:- ()N Copied: 17145 (l:2.6 GB p:2.42 GB) Propagated: 0 (l:Zero KB p:Zero KB) Propagated (shallow): 0 (l:Zero KB p:Zero KB) Tracker: FlCp:13961DrCp:2723SmCp:461 Lookups: Comparisons: Operations: Backup Projected Stats: 1490116 items (p:1.03 TB) Sized: l:5.78 GB p:2.56 GB c:30501, Outstanding:0/62, Finished Volumes:0 Current: Zero KB/(l:1 KB,p:4 KB) - /Volumes/MacBook Pro SSD 2 1/2024-05-24-183819.inprogress/Macintosh HD - Data/Library/Application Support/Malwarebytes/MBAM/Engine.bundle/Contents/PlugIns/RTProtectionDaemon.app/Contents/Resources/logConfig.plist 2024-05-24 18:38:57.989 I backupd[3019:19019] [com.apple.TimeMachine:BackupDispatching] Backup requested to destination with ID 44979773-4BCB-42C4-8626-70779AE14E22. specifiedOptions: TMBackupOptions(rawValue: 16) 2024-05-24 18:38:57.989 I backupd[3019:19019] [com.apple.TimeMachine:BackupJobs] Dropping backup job request. Only one backup at a time. 2024-05-24 18:38:57.990 E backupd[3019:19019] [com.apple.TimeMachine:BackupDispatching] Backup failed: BACKUP_IN_PROGRESS_REQUEST_DROPPED (46) Elapsed time: Calculating... 2024-05-24 18:39:21.958 I backupd[3019:18fda] [com.apple.TimeMachine:CopyProgress] Calculated first time remaining estimate. Waiting for more samples... 2024-05-24 18:39:21.958 I backupd[3019:18fda] [com.apple.TimeMachine:CopyProgress] Calculated first time remaining estimate. Waiting for more samples... 2024-05-24 18:41:15.513 E backupd[3019:199bc] [com.apple.TimeMachine:General] com.apple.backupd.sandbox.xpc: connection invalid 2024-05-24 18:43:42.950 I backupd[3019:18fda] [com.apple.TimeMachine:CopyProgress] .• . Progress: 13% done, 147.87%/h, 481.14 MB/s, avg: 481.14 MB/s, 466.70 items/s, avg: 466.70 items/s ByPhysicalSize:15% (CV:146.75 GB/951.13 GB (D: Zero KB),T:951.13 GB,D:Zero KB(-),LA:Zero KB(-))Y ByItemCount:11% (CV:154730/1457614 (D: 0),T:1457614,D:0(-),LA:0(-))Y ByEventPath:- ()N Copied: 157162 (l:147.35 GB p:146.76 GB) Propagated: 0 (l:Zero KB p:Zero KB) Propagated (shallow): 0 (l:Zero KB p:Zero KB) Tracker: FlCp:124278DrCp:29705SmCp:2432FlLn:68FlCl:679 Lookups: Comparisons: Operations: Backup Projected Stats: 1490116 items (p:1.03 TB) Sized: l:5.86 GB p:2.64 GB c:32501, Outstanding:0/66, Finished Volumes:0 Current: Zero KB/(l:262 KB,p:266 KB) - /Volumes/MacBook Pro SSD 2 1/2024-05-24-183819.inprogress/Macintosh HD - Data/Users/donawalt/Pictures/Photos Library.photoslibrary/resources/derivatives/0/04FC2DFD-3E5D-432E-9D3C-4E077B2A4630_1_105_c.jpeg 2024-05-24 18:45:13.943 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.system.powersources.source 2024-05-24 18:45:18.988 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.system.powersources.source 2024-05-24 18:45:23.957 I backupd-helper[1248:1918] [com.apple.TimeMachine:BackupScheduling] Running for notifyd event com.apple.system.powersources.source 2024-05-24 18:48:42.949 I backupd[3019:18fda] [com.apple.TimeMachine:CopyProgress] .•• . Progress: 23% done, 116.51%/h, 326.62 MB/s, avg: 403.88 MB/s, 443.38 items/s, avg: 455.04 items/s ByPhysicalSize:26% (CV:244.64 GB/951.13 GB (D: Zero KB),T:951.13 GB,D:Zero KB(-),LA:Zero KB(-))Y ByItemCount:20% (CV:287745/1457614 (D: 0),T:1457614,D:0(-),LA:0(-))Y ByEventPath:- ()N Copied: 290177 (l:245.06 GB p:244.75 GB) Propagated: 0 (l:Zero KB p:Zero KB) Propagated (shallow): 0 (l:Zero KB p:Zero KB) Tracker: FlCp:257181DrCp:29755SmCp:2432FlLn:68FlCl:741 Lookups: Comparisons: Operations: Backup Projected Stats: 1490116 items (p:1.03 TB) Sized: l:5.86 GB p:2.64 GB c:32501, Outstanding:0/66, Finished Volumes:0 Current: Zero KB/(l:1.5 MB,p:1.5 MB) - /Volumes/MacBook Pro SSD 2 1/2024-05-24-183819.inprogress/Macintosh HD - Data/Users/donawalt/Pictures/Photos Library.photoslibrary/originals/B/B8E3F6FD-CBAF-4F80-9EAE-20686BDA9933.jpeg 2024-05-24 18:53:42.997 I backupd[3019:18fda] [com.apple.TimeMachine:CopyProgress] .••• . Progress: 35% done, 142.67%/h, 555.46 MB/s, avg: 454.40 MB/s, 219.49 items/s, avg: 376.52 items/s ByPhysicalSize:44% (CV:417.77 GB/944.96 GB (D: Zero KB),T:944.96 GB,D:Zero KB(-),LA:Zero KB(-))Y ByItemCount:25% (CV:353597/1412597 (D: 0),T:1412597,D:0(-),LA:0(-))Y ByEventPath:- ()N Copied: 356031 (l:411.54 GB p:411.4 GB) Propagated: 0 (l:Zero KB p:Zero KB) Propagated (shallow): 0 (l:Zero KB p:Zero KB) Tracker: FlCp:322385DrCp:30378SmCp:2434FlLn:68FlCl:766 Lookups: Comparisons: Operations: Backup Projected Stats: 1490116 items (p:1.03 TB) Sized: l:11.94 GB p:8.81 GB c:77518, Outstanding:0/171, Finished Volumes:0 Current: 6.51 GB/(l:6.51 GB,p:27.29 GB) - /Volumes/MacBook Pro SSD 2 1/2024-05-24-183819.inprogress/Macintosh HD - Data/Users/donawalt/Library/Application Support/Verbum/Data/wtgkuhrc.zr4/LibraryIndex/index.idx 2024-05-24 18:57:03.139 I backupd-helper[1248:1d3b5] [com.apple.TimeMachine:PowerManagement] TMPowerState: 2 2024-05-24 18:57:03.146 I backupd-helper[1248:178b1] [com.apple.TimeMachine:BackupScheduling] Skipping scheduled Time Machine backup: Backup already running 2024-05-24 18:58:43.033 I backupd[3019:18fda] [com.apple.TimeMachine:CopyProgress] .•••• . Progress: 47% done, 151.03%/h, 464.52 MB/s, avg: 456.93 MB/s, 530.93 items/s, avg: 415.13 items/s ByPhysicalSize:58% (CV:549 GB/944.84 GB (D: Zero KB),T:944.84 GB,D:Zero KB(-),LA:Zero KB(-))Y ByItemCount:36% (CV:512573/1411597 (D: 0),T:1411597,D:0(-),LA:0(-))Y ByEventPath:- ()N Copied: 515328 (l:550.86 GB p:550.78 GB) Propagated: 0 (l:Zero KB p:Zero KB) Propagated (shallow): 0 (l:Zero KB p:Zero KB) Tracker: FlCp:409530DrCp:101696SmCp:2755FlLn:68FlCl:1279 Lookups: Comparisons: Operations: Backup Projected Stats: 1490116 items (p:1.03 TB) Sized: l:12.06 GB p:8.93 GB c:78518, Outstanding:0/173, Finished Volumes:0 Current: Zero KB/(l:52.7 MB,p:52.7 MB) - /Volumes/MacBook Pro SSD 2 1/2024-05-24-183819.inprogress/Macintosh HD - Data/Users/donawalt/Library/Group Containers/243LU875E5.groups.com.apple.podcasts/Library/Cache/73C74C44-9007-4649-BF3F-CACD6142E45D.mp3 2024-05-24 19:00:22.909 E backupd[3790:1dd08] [com.apple.TimeMachine:General] com.apple.backupd.sandbox.xpc: connection invalid 2024-05-24 19:00:22.909 E backupd[3790:1dd09] [com.apple.TimeMachine:General] com.apple.backupd.xpc: connection invalid 2024-05-24 19:00:22.909 E backupd[3790:1dd0a] [com.apple.TimeMachine:General] com.apple.backupd.status.xpc: connection invalid donawalt@Bartholomew-M3-Max ~ %