Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Really looking forward to this, but unfortunately not working on MBA M2 with Sonoma 14.2.1 #2

Open
brahov opened this issue Jan 8, 2024 · 3 comments

Comments

@brahov
Copy link

brahov commented Jan 8, 2024

Hi Brian,

Let's start of by thanking you for making this. I've been looking all over!

Unfortunatly, the software currently isn't working for me. I've installed using Mint and can see both processes (UnmountVolumeAfterTimeMachine & DiskUnmountWatcher) appearing in Activity Monitor after connecting my disk.

After TimeMachine is done, the disk doesn't get unmounted. I've used console to dig up some debugging logs and this is what I found:

`default 21:59:17.459872+0100 kernel spaceman_iterate_free_extents_internal:3207: disk5 nx_unmount detected while processing dev=0 cib=1 out of 60 cibs
default 21:59:20.596355+0100 mds openStoresByUUIDArray volume:0x11f1f8bd7 unmounting:0 goingAway:0 shut:0 qos:0x11 count:1
default 21:59:20.627201+0100 UnmountVolumeAfterTimeMachine No launchd agent set
default 21:59:20.628757+0100 UnmountVolumeAfterTimeMachine launchd agent written
default 21:59:20.629981+0100 UnmountVolumeAfterTimeMachine launchd agent startOnMount set
default 21:59:20.651477+0100 UnmountVolumeAfterTimeMachine status: 0x600000fd80c0ublic(null)
default 21:59:20.651916+0100 UnmountVolumeAfterTimeMachine [0x6000025d0000] activating connection: mach=true listener=false peer=false name=com.apple.cfprefsd.daemon
default 21:59:20.651989+0100 UnmountVolumeAfterTimeMachine [0x6000025d4000] activating connection: mach=true listener=false peer=false name=com.apple.cfprefsd.agent
default 21:59:20.779112+0100 backgroundtaskmanagementd registerLaunchItem: found existing item: uuid=07334784-526E-46D9-BE30-431AF08F998E, name=UnmountVolumeAfterTimeMachine, type=legacy agent, disposition=[enabled, allowed, visible, notified], identifier=ie.BrianHenry.UnmountVolumeAfterTimeMachine, url=file:///Users/bramvandenhoven/Library/LaunchAgents/ie.BrianHenry.UnmountVolumeAfterTimeMachine.plist
default 21:59:20.779154+0100 backgroundtaskmanagementd registerLaunchItem: checking for an updated legacy agent or daemon item: uuid=07334784-526E-46D9-BE30-431AF08F998E, name=UnmountVolumeAfterTimeMachine, type=legacy agent, disposition=[enabled, allowed, visible, notified], identifier=ie.BrianHenry.UnmountVolumeAfterTimeMachine, url=file:///Users/bramvandenhoven/Library/LaunchAgents/ie.BrianHenry.UnmountVolumeAfterTimeMachine.plist
default 21:59:20.826874+0100 backgroundtaskmanagementd registerLaunchItem: updated item with same LWCR: uuid=07334784-526E-46D9-BE30-431AF08F998E, name=UnmountVolumeAfterTimeMachine, type=legacy agent, disposition=[enabled, allowed, visible, notified], identifier=ie.BrianHenry.UnmountVolumeAfterTimeMachine, url=file:///Users/bramvandenhoven/Library/LaunchAgents/ie.BrianHenry.UnmountVolumeAfterTimeMachine.plist
default 21:59:20.827419+0100 backgroundtaskmanagementd effectiveItemDisposition: appURL=(null), type=legacy agent, url=file:///Users/bramvandenhoven/Library/LaunchAgents/ie.BrianHenry.UnmountVolumeAfterTimeMachine.plist, config={
BTMConfigArguments = (
);
BTMConfigBundleIdentifiers = (
);
BTMConfigExecutablePath = "/Users/bramvandenhoven/.mint/packages/github.com_BrianHenryIE_UnmountVolumeAfterTimeMachine/build/master/UnmountVolumeAfterTimeMachine";
BTMConfigLabel = "ie.BrianHenry.UnmountVolumeAfterTimeMachine";
}
default 21:59:21.086249+0100 tccd AUTHREQ_ATTRIBUTION: msgID=361.54, attribution={responsible={TCCDProcess: identifier=UnmountVolumeAfterTimeMachine, pid=1311, auid=501, euid=501, responsible_path=/Users/bramvandenhoven/.mint/packages/github.com_BrianHenryIE_UnmountVolumeAfterTimeMachine/build/master/UnmountVolumeAfterTimeMachine, binary_path=/Users/bramvandenhoven/.mint/packages/github.com_BrianHenryIE_UnmountVolumeAfterTimeMachine/build/master/UnmountVolumeAfterTimeMachine}, accessing={TCCDProcess: identifier=com.apple.timemachine.tmutil, pid=1318, auid=501, euid=501, binary_path=/usr/bin/tmutil}, requesting={TCCDProcess: identifier=com.apple.sandboxd, pid=361, auid=0, euid=0, binary_path=/usr/libexec/sandboxd}, },
default 21:59:21.096177+0100 tccd AUTHREQ_SUBJECT: msgID=361.54, subject=/Users/bramvandenhoven/.mint/packages/github.com_BrianHenryIE_UnmountVolumeAfterTimeMachine/build/master/UnmountVolumeAfterTimeMachine,
default 21:59:21.098397+0100 tccd -[TCCDAccessIdentity staticCode]: static code for: identifier /Users/bramvandenhoven/.mint/packages/github.com_BrianHenryIE_UnmountVolumeAfterTimeMachine/build/master/UnmountVolumeAfterTimeMachine, type: 1: 0x15a12dcd0 at /Users/bramvandenhoven/.mint/packages/github.com_BrianHenryIE_UnmountVolumeAfterTimeMachine/build/master/UnmountVolumeAfterTimeMachine
default 21:59:21.103827+0100 tccd Publishing <TCCDEvent: type=Create, service=kTCCServiceSystemPolicyAllFiles, identifier_type=Path, identifier=/Users/bramvandenhoven/.mint/packages/github.com_BrianHenryIE_UnmountVolumeAfterTimeMachine/build/master/UnmountVolumeAfterTimeMachine> to 0 subscribers: {
}
error 21:59:21.259923+0100 UnmountVolumeAfterTimeMachine Could not parse line:

error 21:59:21.316117+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 21:59:21.323663+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 21:59:21.325680+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 21:59:21.926613+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 21:59:21.927958+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 21:59:21.928782+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 21:59:21.929429+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 21:59:22.082093+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 21:59:22.201643+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:10.471842+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:10.478337+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:10.574641+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:10.595633+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:11.291917+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:11.322609+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:12.561949+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:12.570943+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:12.572861+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:13.174540+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:13.857530+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:14.067207+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:14.081307+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:14.087635+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:16.828457+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:16.867259+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:17.319103+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:17.322527+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:18.450134+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:18.486324+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:18.516930+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:18.516997+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:18.517040+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:18.517254+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:18.744609+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:18.762561+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:18.815260+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:18.843914+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:18.849390+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:18.856254+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:19.342114+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:19.948147+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:19.994831+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:20.999711+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:20.020036+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:20.023021+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:20.093293+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:20.804619+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:22.556375+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:22.556935+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:22.558450+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:22.660784+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:22.664397+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:22.691138+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:23.766680+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:23.766829+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:23.767836+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:23.771585+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:23.774735+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:23.774830+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:29.862929+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:43.806308+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:43.989729+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:44.999042+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:00:52.921618+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:18.195266+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:32.800400+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:32.803100+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:33.646553+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:33.646761+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:33.647341+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:33.720299+0100 UnmountVolumeAfterTimeMachine Could not parse line: default 22:01:33.777791+0100 mds BEGIN diskUnmountApprovalCallback default 22:01:33.777832+0100 mds END diskUnmountApprovalCallback - no volume default 22:01:33.778810+0100 mdsync diskUnmountApprovalCallback received:838860806 default 22:01:33.778978+0100 photolibraryd Unmount of this disk is not interesting. Does not match default 22:01:33.781601+0100 com.apple.CloudPhotosConfiguration Unmount of this disk is not interesting. Does not match default 22:01:33.781562+0100 com.apple.CloudPhotosConfiguration Unmount of this disk is not interesting. Does not match default 22:01:33.787148+0100 photolibraryd Unmount of this disk is not interesting. Does not match default 22:01:33.789962+0100 cloudphotod Unmount of volume (null) () is not interesting. Does not match default 22:01:33.790328+0100 diskarbitrationd unmounted disk, id = /Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/EmpreTECH-Macbook/2024-01-08-215354/Data?owner=0, ongoing. default 22:01:33.794327+0100 kernel apfs_log_op_with_proc:3110: disk3s5 unmounting volume com.apple.TimeMachine.2024-01-08-215354.local, requested by: diskarbitrationd (pid 330); parent: launchd (pid 1) default 22:01:33.796799+0100 diskarbitrationd unmounted disk, id = /Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/EmpreTECH-Macbook/2024-01-08-215354/Data?owner=0, success. default 22:01:33.794352+0100 kernel apfs_vfsop_unmount:3471: disk3s5 snapshot deletion completed on the livefs default 22:01:33.794356+0100 kernel apfs_vfsop_unmount:3537: disk3 nx_num_vols_mounted is 6 default 22:01:33.794358+0100 kernel apfs_vfsop_unmount:3550: all done. going home. (numMountedAPFSVolumes 11) default 22:01:33.884161+0100 DiskUnmountWatcher DiskUnmountWatcher: main default 22:01:33.884340+0100 DiskUnmountWatcher [0x153904f80] activating connection: mach=true listener=true peer=false name=com.apple.diskarbitration default 22:01:33.884530+0100 DiskUnmountWatcher DiskUnmountWatcher: main default 22:01:33.884522+0100 DiskUnmountWatcher [0x1539069f0] activating connection: mach=false listener=false peer=true name=com.apple.diskarbitration.peer.0x1539069f0 default 22:01:33.884638+0100 DiskUnmountWatcher DiskUnmountWatcher: callback default 22:01:33.884723+0100 DiskUnmountWatcher DiskUnmountWatcher: callback find and release default 22:01:33.884809+0100 DiskUnmountWatcher NAHFindByLabelAndRelease: looking for label default 22:01:33.885932+0100 DiskUnmountWatcher [0x152e0af40] activating connection: mach=true listener=false peer=false name=com.apple.cfprefsd.daemon default 22:01:33.886025+0100 DiskUnmountWatcher [0x152e0b210] activating connection: mach=true listener=false peer=false name=com.apple.cfprefsd.agent default 22:01:33.894952+0100 DiskUnmountWatcher [0x152f056d0] activating connection: mach=true listener=false peer=false name=com.apple.GSSCred error 22:01:33.955313+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:33.956298+0100 UnmountVolumeAfterTimeMachine Could not parse line: default 22:01:33.978935+0100 mds BEGIN diskUnmountApprovalCallback default 22:01:33.978971+0100 mds END diskUnmountApprovalCallback - no volume default 22:01:33.979546+0100 photolibraryd Unmount of this disk is not interesting. Does not match default 22:01:33.979674+0100 photolibraryd Unmount of this disk is not interesting. Does not match default 22:01:33.979856+0100 mdsync diskUnmountApprovalCallback received:838860805 default 22:01:33.980010+0100 com.apple.CloudPhotosConfiguration Unmount of this disk is not interesting. Does not match default 22:01:33.980145+0100 com.apple.CloudPhotosConfiguration Unmount of this disk is not interesting. Does not match default 22:01:33.982522+0100 cloudphotod Unmount of volume (null) () is not interesting. Does not match default 22:01:33.982961+0100 diskarbitrationd unmounted disk, id = /Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/EmpreTECH-Macbook/2024-01-08-220018/Data?owner=0, ongoing. default 22:01:34.000271+0100 diskarbitrationd unmounted disk, id = /Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/EmpreTECH-Macbook/2024-01-08-220018/Data?owner=0, failure. error 22:01:34.000308+0100 diskarbitrationd unable to unmount /Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/EmpreTECH-Macbook/2024-01-08-220018/Data?owner=0 (status code 0x00000010). default 22:01:34.013648+0100 backupd DADisk_callback IntErr=1=0x1 Unmount of unknown blocked by dissenter PID=376 (/usr/libexec/lsd) PPID=1 (/sbin/launchd) status=0x0000c010 (File system reports this error: Resource busy) default 22:01:34.155205+0100 backupd waitForDACallBack IntErr=1=0x1 retSuccess=0 inDictToMonitor=now={ Action = Unmount; Dissenter = 1; DissenterPID = 376; DissenterPPID = 1; DissenterStatus = 49168; Target = "file:///Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/EmpreTECH-Macbook/2024-01-08-220018/Data/"; } default 22:01:34.155379+0100 backupd DMManager(Mount) unmountVolumePath IntErr=1=0x1 retSuccess=0 outDict={ Action = Unmount; Dissenter = 1; DissenterPID = 376; DissenterPPID = 1; DissenterStatus = 49168; Target = "file:///Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/EmpreTECH-Macbook/2024-01-08-220018/Data/"; } error 22:01:34.159199+0100 backupd Failed to unmount '/Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/EmpreTECH-Macbook/2024-01-08-220018/Data', Disk Management error: { Action = Unmount; Dissenter = 1; DissenterPID = 376; DissenterPPID = 1; DissenterStatus = 49168; Target = "file:///Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/EmpreTECH-Macbook/2024-01-08-220018/Data/"; } error 22:01:34.159557+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:34.160515+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:34.191823+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:34.367058+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:35.829694+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:35.829754+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:36.433135+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:36.437072+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:36.438981+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:36.453577+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:37.513942+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:37.532088+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:01:37.532363+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:02:19.010205+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:02:21.244828+0100 UnmountVolumeAfterTimeMachine Could not parse line: error 22:02:21.289592+0100 UnmountVolumeAfterTimeMachine Could not parse line: default 22:02:21.561573+0100 Console update collaboration items for activityItems:( "default\t21:59:17.459872+0100\tkernel\tspaceman_iterate_free_extents_internal:3207: disk5 nx_unmount detected while processing dev=0 cib=1 out of 60 cibs\ndefault\t21:59:20.596355+0100\tmds\topenStoresByUUIDArray volume:0x11f1f8bd7 unmounting:0 goingAway:0 shut:0 qos:0x11 count:1\ndefault\t21:59:20.627201+0100\tUnmountVolumeAfterTimeMachine\tNo launchd agent set\ndefault\t21:59:20.628757+0100\tUnmountVolumeAfterTimeMachine\tlaunchd agent written\ndefault\t21:59:20.629981+0100\tUnmountVolumeAfterTimeMachine\tlaunchd agent startOnMount set\ndefault\t21:59:20.651477+0100\tUnmountVolumeAfterTimeMachine\tstatus: 0x600000fd80c0ublic(null)\ndefault\t21:59:20.651916+0100\tUnmountVolumeAfterTimeMachine\t[0x6000025d0000] activating connection: mach=true listener=false peer=false name=com.apple.cfprefsd.daemon\ndefault\t21:59:20.651989+0100\tUnmountVolumeAfterTimeMachine\t[0x6000025d4000] activating connection: mach=true listener=false peer=false name=com.apple.cfprefsd.agent\ndefault\t21:59:20.779112+01 default 22:02:21.561671+0100 Console request collaboration items for activity items:( "default\t21:59:17.459872+0100\tkernel\tspaceman_iterate_free_extents_internal:3207: disk5 nx_unmount detected while processing dev=0 cib=1 out of 60 cibs\ndefault\t21:59:20.596355+0100\tmds\topenStoresByUUIDArray volume:0x11f1f8bd7 unmounting:0 goingAway:0 shut:0 qos:0x11 count:1\ndefault\t21:59:20.627201+0100\tUnmountVolumeAfterTimeMachine\tNo launchd agent set\ndefault\t21:59:20.628757+0100\tUnmountVolumeAfterTimeMachine\tlaunchd agent written\ndefault\t21:59:20.629981+0100\tUnmountVolumeAfterTimeMachine\tlaunchd agent startOnMount set\ndefault\t21:59:20.651477+0100\tUnmountVolumeAfterTimeMachine\tstatus: 0x600000fd80c0ublic(null)\ndefault\t21:59:20.651916+0100\tUnmountVolumeAfterTimeMachine\t[0x6000025d0000] activating connection: mach=true listener=false peer=false name=com.apple.cfprefsd.daemon\ndefault\t21:59:20.651989+0100\tUnmountVolumeAfterTimeMachine\t[0x6000025d4000] activating connection: mach=true listener=false peer=false name=com.apple.cfprefsd.agent\ndefault\t21:59:20.779112+01

`

@BrianHenryIE
Copy link
Owner

I'm still running 14.1.1 on M1. Thanks for the logs.

My guess at what's happening is the syslog format is slightly different now. I had written a log parsing library, which UnmountVolumeAfterTimeMachine uses, before I learned that there is a supported MacOS API for reading logs now. Hopefully it's a simple to fix as changing to use that instead.

Might be a while before I look at this still.

@brahov
Copy link
Author

brahov commented Jan 9, 2024 via email

@BrianHenryIE
Copy link
Owner

Hey, sorry for the delay. About two months later I finally ran another backup and I've run a few since and it has always worked. MBA M1 on I think the latest now.

Please try again and let me know

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants