search

mbentley / docker-timemachine

Docker image to run Samba (compatible Time Machine for macOS)
Apache License 2.0
527 stars 65 forks source link

Unable to resolve permission denied error #146

Closed spence10101 closed 7 months ago

spence10101 commented 11 months ago

Describe the Bug

I backed up to an older version of this container for a while. I upgraded the container, but forgot to keep anything but the data (including the config volumes and run command). Now, Time Machine sees the backup and asks to reattach, but gets a permission error when attempting to back up.

Expected Behavior

Back up without permission error

Steps to Reproduce

  1. Backed up using old container
  2. Removed container completely, kept data directory
  3. Created new container - pointed data directory appropriately
  4. Selected backup location in TimeMachine (from same MacBook)

How You're Launching the Container

docker run -d --restart=always \
  --name timemachine \
  --network macvlan1 \
  --ip 192.168.1.7 \
  -e PASSWORD="***" \
  -v /mnt/tank/timemachine:/opt/timemachine \
  -v timemachine-var-lib-samba:/var/lib/samba \
  -v timemachine-var-cache-samba:/var/cache/samba \
  -v timemachine-run-samba:/run/samba \
  mbentley/timemachine:smb

Container Logs

INFO: CUSTOM_SMB_CONF=false; generating [global] section of /etc/samba/smb.conf...
INFO: Creating /var/log/samba/cores
INFO: Avahi - generating base configuration in /etc/avahi/services/smbd.service...
INFO: Avahi - adding the 'dk0', 'TimeMachine' share txt-record to /etc/avahi/services/smbd.service...
INFO: Group timemachine doesn't exist; creating...
INFO: User timemachine doesn't exist; creating...
INFO: Setting password from environment variable
chpasswd: password for 'timemachine' changed
INFO: INFO: CUSTOM_SMB_CONF=false; generating [TimeMachine] section of /etc/samba/smb.conf...
INFO: Samba - Created Added user timemachine.
INFO: Samba - Enabled user timemachine.
INFO: Samba - setting password
INFO: SET_PERMISSIONS=false; not setting ownership and permissions for /opt/timemachine
INFO: Avahi - completing the configuration in /etc/avahi/services/smbd.service...
INFO: running test for xattr support on your time machine persistent storage location...
INFO: xattr test successful - your persistent data store supports xattrs
INFO: Detected filesystem for /opt/timemachine is ext4
INFO: entrypoint complete; executing 's6-svscan /etc/s6'
dbus socket not yet available; sleeping...
nmbd version 4.18.5 started.
Copyright Andrew Tridgell and the Samba Team 1992-2023
smbd version 4.18.5 started.
Copyright Andrew Tridgell and the Samba Team 1992-2023
INFO: Profiling support unavailable in this build.
Found user 'avahi' (UID 86) and group 'avahi' (GID 86).
Successfully dropped root privileges.
avahi-daemon 0.8 starting up.
WARNING: No NSS support for mDNS detected, consider installing nss-mdns!
Loading service file /etc/avahi/services/smbd.service.
Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.7.
New relevant interface eth0.IPv4 for mDNS.
Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1.
New relevant interface lo.IPv4 for mDNS.
Network interface enumeration completed.
Registering new address record for 192.168.1.7 on eth0.IPv4.
Registering new address record for 127.0.0.1 on lo.IPv4.
Server startup complete. Host name is dea3cf32572a.local. Local service cookie is 704279998.
Service "dea3cf32572a" (/etc/avahi/services/smbd.service) successfully established.

Additional Context

Here are logs from TimeMachine:

2023-08-20 16:22:30  Attempting to mount 'smb://timemachine@dea3cf32572a._smb._tcp.local./TimeMachine'
2023-08-20 16:22:31  Initial network volume options for 'TimeMachine' {disablePrimaryReconnect: 0, disableSecondaryReconnect: 0, reconnectTimeOut: 0, QoS: 0x0, attributes: 0x1C}
2023-08-20 16:22:31  Configured network volume options for 'TimeMachine' {disablePrimaryReconnect: 0, disableSecondaryReconnect: 0, reconnectTimeOut: 30, QoS: 0x20, attributes: 0x1C}
2023-08-20 16:22:31  Mounted 'smb://timemachine@dea3cf32572a._smb._tcp.local./TimeMachine' at '/Volumes/.timemachine/dea3cf32572a._smb._tcp.local./1F90C358-FD0D-4550-84C2-B3689ED5A21B/TimeMachine' (14.26 TB of 17.93 TB available)
2023-08-20 16:22:32  Skipping periodic backup verification: no previous backups to this destination.
2023-08-20 16:22:32  Failed to open '/Volumes/.timemachine/dea3cf32572a._smb._tcp.local./1F90C358-FD0D-4550-84C2-B3689ED5A21B/TimeMachine/Jake’s MacBook Pro.sparsebundle/com.apple.TimeMachine.MachineID.bckup' for writing, error: 13 Permission denied
2023-08-20 16:22:32  Mountpoint '/Volumes/.timemachine/dea3cf32572a._smb._tcp.local./1F90C358-FD0D-4550-84C2-B3689ED5A21B/TimeMachine' is still valid
2023-08-20 16:22:32  Checking for runtime corruption on '/Volumes/.timemachine/dea3cf32572a._smb._tcp.local./1F90C358-FD0D-4550-84C2-B3689ED5A21B/TimeMachine/Jake’s MacBook Pro.sparsebundle'
2023-08-20 16:22:32  Failed to attach using DiskImages2 to url '/Volumes/.timemachine/dea3cf32572a._smb._tcp.local./1F90C358-FD0D-4550-84C2-B3689ED5A21B/TimeMachine/Jake’s MacBook Pro.sparsebundle', error: Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied" UserInfo={DIErrorVerboseInfo=Failed opening the file with RW access: Couldn't open Info.plist' with flags 0x102, UID:GID(Name)=0:wheel mode=0x81c0 (our euid is 0)}
2023-08-20 16:22:32  Unmounted '/Volumes/.timemachine/dea3cf32572a._smb._tcp.local./1F90C358-FD0D-4550-84C2-B3689ED5A21B/TimeMachine'
2023-08-20 16:22:32  Backup failed (21: BACKUP_FAILED_DISK_IMAGE_NOT_MOUNTED)

Here's the permissions from the host:

spence@zeus:~$ ls -lahn /mnt/tank/timemachine
total 28K
drwxr-sr-x  3 1000 1000 4.0K Aug 20 16:22  .
drwxr-xr-x  7    0    0 4.0K Aug  2  2021  ..
-rwxrwxr-x+ 1 1000 1000 6.1K Aug 20 14:32  .DS_Store
drwxrwsr-x+ 4 1000 1000 4.0K Dec  7  2021 'Jake’s MacBook Pro.sparsebundle'
mbentley commented 7 months ago

Sorry that I never got around to responding. Not sure if you are still having issue or not but I'll say that debugging errors beyond the initial connection to time machine isn't my strong suit as a lot of the macos processes that run to actually do the backup are not something I am familiar with and there are many messages that look like errors but don't impact the backup process which makes it even harder to filter through logs to find the actual culprit. Unfortunately in many of these situations, it tends to be easiest to clear persistent data and start a new time machine backup.

mbentley commented 7 months ago

Funny enough, my laptop starting getting some similar errors this morning when I was attempting to do a manual backup. Just dropping in logs for when I am able to actually do some comparisons to figure out what is actually an error.

Failure on my m1 macbook

This was an incremental backup; I had just started a new time machine backup less than a week ago because the sparsebundle seemed to be corrupted.

2023-12-14 09:35:13  User tried to start a backup
2023-12-14 09:35:13  Backup requested to next destination in rotation. specifiedOptions: TMBackupOptions(rawValue: 0)
2023-12-14 09:35:13  Not prioritizing backups with priority errors. lockState=0
2023-12-14 09:35:13  Starting backup with mode "manual backup"
2023-12-14 09:35:13  Attempting to mount 'smb://timemachine@timemachine._smb._tcp.local./TimeMachine'
2023-12-14 09:35:15  Initial network volume options for 'TimeMachine' {disablePrimaryReconnect: 0, disableSecondaryReconnect: 0, reconnectTimeOut: 0, QoS: 0x0, attributes: 0x1C}
2023-12-14 09:35:15  Configured network volume options for 'TimeMachine' {disablePrimaryReconnect: 0, disableSecondaryReconnect: 0, reconnectTimeOut: 30, QoS: 0x20, attributes: 0x1C}
2023-12-14 09:35:15  Mounted 'smb://timemachine@timemachine._smb._tcp.local./TimeMachine' at '/Volumes/.timemachine/timemachine._smb._tcp.local./DA1BEFC4-8550-4618-A176-250E66C1B1F5/TimeMachine' (2.58 TB of 3.3 TB available)
2023-12-14 09:35:15  Mountpoint '/Volumes/.timemachine/timemachine._smb._tcp.local./DA1BEFC4-8550-4618-A176-250E66C1B1F5/TimeMachine' is still valid
2023-12-14 09:35:16  Skipping periodic backup verification: not needed for an APFS sparsebundle
2023-12-14 09:35:16  'armbook.sparsebundle' does not need resizing - current logical size is 16 TB (16,000,000,000,000 bytes), size limit is 3.13 TB (3,133,608,139,161 bytes)
2023-12-14 09:35:16  Checking for runtime corruption on '/Volumes/.timemachine/timemachine._smb._tcp.local./DA1BEFC4-8550-4618-A176-250E66C1B1F5/TimeMachine/armbook.sparsebundle'
2023-12-14 09:35:16  Failed to attach using DiskImages2 to url '/Volumes/.timemachine/timemachine._smb._tcp.local./DA1BEFC4-8550-4618-A176-250E66C1B1F5/TimeMachine/armbook.sparsebundle', error: Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied" UserInfo={DIErrorVerboseInfo=Failed opening the file with RW access: Couldn't open Info.bckup' with flags 0x102, UID:GID(Name)=0:wheel mode=0x81c0 (our euid is 0)}
2023-12-14 09:35:16  Evaluating to see if an unmount was responsible for error: Error Domain=com.apple.backupd.ErrorDomain Code=42 "(null)" UserInfo={Retryable=true, MessageParameters=(
2023-12-14 09:35:16  Mountpoint '/Volumes/.timemachine/timemachine._smb._tcp.local./DA1BEFC4-8550-4618-A176-250E66C1B1F5/TimeMachine' is still valid
2023-12-14 09:35:16  Unmounted '/Volumes/.timemachine/timemachine._smb._tcp.local./DA1BEFC4-8550-4618-A176-250E66C1B1F5/TimeMachine'
2023-12-14 09:35:16  Waiting 60 seconds and trying again.
2023-12-14 09:36:20  Attempting to mount 'smb://timemachine@timemachine._smb._tcp.local./TimeMachine'
2023-12-14 09:36:21  Initial network volume options for 'TimeMachine' {disablePrimaryReconnect: 0, disableSecondaryReconnect: 0, reconnectTimeOut: 0, QoS: 0x0, attributes: 0x1C}
2023-12-14 09:36:22  Configured network volume options for 'TimeMachine' {disablePrimaryReconnect: 0, disableSecondaryReconnect: 0, reconnectTimeOut: 30, QoS: 0x20, attributes: 0x1C}
2023-12-14 09:36:22  Mounted 'smb://timemachine@timemachine._smb._tcp.local./TimeMachine' at '/Volumes/.timemachine/timemachine._smb._tcp.local./6C972792-C529-4D26-979A-76524B9D8EDE/TimeMachine' (2.58 TB of 3.3 TB available)
2023-12-14 09:36:22  Mountpoint '/Volumes/.timemachine/timemachine._smb._tcp.local./6C972792-C529-4D26-979A-76524B9D8EDE/TimeMachine' is still valid
2023-12-14 09:36:22  Skipping periodic backup verification: not needed for an APFS sparsebundle
2023-12-14 09:36:22  Checking for runtime corruption on '/Volumes/.timemachine/timemachine._smb._tcp.local./6C972792-C529-4D26-979A-76524B9D8EDE/TimeMachine/armbook.sparsebundle'
2023-12-14 09:36:23  Failed to attach using DiskImages2 to url '/Volumes/.timemachine/timemachine._smb._tcp.local./6C972792-C529-4D26-979A-76524B9D8EDE/TimeMachine/armbook.sparsebundle', error: Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied" UserInfo={DIErrorVerboseInfo=Failed opening the file with RW access: Couldn't open Info.bckup' with flags 0x102, UID:GID(Name)=0:wheel mode=0x81c0 (our euid is 0)}
2023-12-14 09:36:23  Evaluating to see if an unmount was responsible for error: Error Domain=com.apple.backupd.ErrorDomain Code=42 "(null)" UserInfo={Retryable=true, MessageParameters=(
2023-12-14 09:36:23  Mountpoint '/Volumes/.timemachine/timemachine._smb._tcp.local./6C972792-C529-4D26-979A-76524B9D8EDE/TimeMachine' is still valid
2023-12-14 09:36:23  Unmounted '/Volumes/.timemachine/timemachine._smb._tcp.local./6C972792-C529-4D26-979A-76524B9D8EDE/TimeMachine'
2023-12-14 09:36:23  Waiting 60 seconds and trying again.
2023-12-14 09:37:27  Attempting to mount 'smb://timemachine@timemachine._smb._tcp.local./TimeMachine'
2023-12-14 09:37:30  Initial network volume options for 'TimeMachine' {disablePrimaryReconnect: 0, disableSecondaryReconnect: 0, reconnectTimeOut: 0, QoS: 0x0, attributes: 0x1C}
2023-12-14 09:37:31  Configured network volume options for 'TimeMachine' {disablePrimaryReconnect: 0, disableSecondaryReconnect: 0, reconnectTimeOut: 30, QoS: 0x20, attributes: 0x1C}
2023-12-14 09:37:31  Mounted 'smb://timemachine@timemachine._smb._tcp.local./TimeMachine' at '/Volumes/.timemachine/timemachine._smb._tcp.local./94D8611B-BE4F-46A0-8468-4B64F8DA7313/TimeMachine' (2.58 TB of 3.3 TB available)
2023-12-14 09:37:31  Mountpoint '/Volumes/.timemachine/timemachine._smb._tcp.local./94D8611B-BE4F-46A0-8468-4B64F8DA7313/TimeMachine' is still valid
2023-12-14 09:37:32  Skipping periodic backup verification: not needed for an APFS sparsebundle
2023-12-14 09:37:32  Checking for runtime corruption on '/Volumes/.timemachine/timemachine._smb._tcp.local./94D8611B-BE4F-46A0-8468-4B64F8DA7313/TimeMachine/armbook.sparsebundle'
2023-12-14 09:37:32  Failed to attach using DiskImages2 to url '/Volumes/.timemachine/timemachine._smb._tcp.local./94D8611B-BE4F-46A0-8468-4B64F8DA7313/TimeMachine/armbook.sparsebundle', error: Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied" UserInfo={DIErrorVerboseInfo=Failed opening the file with RW access: Couldn't open Info.bckup' with flags 0x102, UID:GID(Name)=0:wheel mode=0x81c0 (our euid is 0)}
2023-12-14 09:37:32  Evaluating to see if an unmount was responsible for error: Error Domain=com.apple.backupd.ErrorDomain Code=42 "(null)" UserInfo={Retryable=true, MessageParameters=(
2023-12-14 09:37:32  Mountpoint '/Volumes/.timemachine/timemachine._smb._tcp.local./94D8611B-BE4F-46A0-8468-4B64F8DA7313/TimeMachine' is still valid
2023-12-14 09:37:32  Unmounted '/Volumes/.timemachine/timemachine._smb._tcp.local./94D8611B-BE4F-46A0-8468-4B64F8DA7313/TimeMachine'
2023-12-14 09:37:32  Finished rotating through all destinations
2023-12-14 09:37:32  Backup failed: BACKUP_HC_NOT_COMPLETED (42)
2023-12-14 09:37:32  Analytics send failed.

Success in a VM

I created a VM, did a 1st backup and then did another to just get a successful backup to compare to:

2023-12-14 23:01:45 
2023-12-14 23:01:45  Failed to handle AppleEvent openParameter, error: Unknown action
2023-12-14 23:01:54  Waiting for Spotlight to finish indexing '/Volumes/Backups of admin’s Virtual Machine'
2023-12-14 23:01:55  User tried to start a backup
2023-12-14 23:01:55  Backup requested to next destination in rotation. specifiedOptions: TMBackupOptions(rawValue: 0)
2023-12-14 23:01:55  Not prioritizing backups with priority errors. lockState=0
2023-12-14 23:01:55  Starting backup with mode "manual backup"
2023-12-14 23:01:55  Network destination already mounted at: /Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine
2023-12-14 23:01:55  Mountpoint '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine' is still valid
2023-12-14 23:02:02  Unmounted '/Volumes/.timemachine/E5C24850-AC3A-4ADB-9B11-BA1B1965ED05/2023-12-14-211523.backup'
2023-12-14 23:02:02  Unmounted '/Volumes/.timemachine/E5C24850-AC3A-4ADB-9B11-BA1B1965ED05/2023-12-14-223100.backup'
2023-12-14 23:02:02  Accepting a new connection from 'Spotlight-mds' for service com.apple.backupd.session.xpc
2023-12-14 23:02:02  Failed to read capabilities for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Operation not permitted
2023-12-14 23:02:02  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x6000026d4330 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:02:02  Failed to create volume info from disk '<TMDisk: 0x6000026d4060> '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine'', error: missingURLForRemounting
2023-12-14 23:02:02  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x60000056c510 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:02:02  Failed to create volume info from disk '<TMDisk: 0x60000056c570> '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine'', error: missingURLForRemounting
2023-12-14 23:02:03  Failed to read capabilities for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Operation not permitted
2023-12-14 23:02:03  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x6000026be5b0 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:02:03  Failed to create volume info from disk '<TMDisk: 0x6000026bd860> '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine'', error: missingURLForRemounting
2023-12-14 23:02:03  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x60000056d230 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:02:03  Failed to create volume info from disk '<TMDisk: 0x60000056ca20> '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine'', error: missingURLForRemounting
2023-12-14 23:02:04  Failed to open '/System/Volumes/Data/Volumes/Backups of admin’s Virtual Machine', error: No such file or directory
2023-12-14 23:02:04  Failed to enumerate instances of structure ReadOnlyBackup under path /System/Volumes/Data/Volumes/Backups of admin’s Virtual Machine, error: Error Domain=NSPOSIXErrorDomain Code=2 "No such file or directory" UserInfo={NSFilePath=/System/Volumes/Data/Volumes/Backups of admin’s Virtual Machine, NSLocalizedDescription=/System/Volumes/Data/Volumes/Backups of admin’s Virtual Machine: No such file or directory (error 2)}
2023-12-14 23:02:04  Failed to unmount '/Volumes/.timemachine/E5C24850-AC3A-4ADB-9B11-BA1B1965ED05/2023-12-14-211523.backup/2023-12-14-211523.backup', error: Error Domain=NSPOSIXErrorDomain Code=2 "No such file or directory"
2023-12-14 23:02:04  Finishing session for '/System/Volumes/Data/Volumes/Backups of admin’s Virtual Machine' with options: 4
2023-12-14 23:02:04  Unmounted '/Volumes/Backups of admin’s Virtual Machine'
2023-12-14 23:02:04  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x60000056be40 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:02:04  Failed to create volume info from disk '<TMDisk: 0x60000056bf60> '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine'', error: missingURLForRemounting
2023-12-14 23:02:04  Failed to read capabilities for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Operation not permitted
2023-12-14 23:02:04  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x6000026b1e60 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:02:04  Failed to create volume info from disk '<TMDisk: 0x6000026b1770> '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine'', error: missingURLForRemounting
2023-12-14 23:02:06  Skipping periodic backup verification: not needed for an APFS sparsebundle
2023-12-14 23:02:06  'admin’s Virtual Machine.sparsebundle' does not need resizing - current logical size is 16 TB (16,000,000,000,000 bytes), size limit is 3.13 TB (3,133,608,139,161 bytes)
2023-12-14 23:02:06  Checking for runtime corruption on '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/admin’s Virtual Machine.sparsebundle'
2023-12-14 23:02:07  Successfully attached using DiskImages2 as 'disk3' from URL '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/admin’s Virtual Machine.sparsebundle'
2023-12-14 23:02:07  Found disk4s1 41504653-0000-11AA-AA11-00306543ECAC
2023-12-14 23:02:07  Found disk4s1 41504653-0000-11AA-AA11-00306543ECAC
2023-12-14 23:02:07  Mountpoint '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine' is still valid
2023-12-14 23:02:07  Found disk4s1 41504653-0000-11AA-AA11-00306543ECAC
2023-12-14 23:02:07  Runtime corruption check passed for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/admin’s Virtual Machine.sparsebundle'
2023-12-14 23:02:07  Found disk4s1 41504653-0000-11AA-AA11-00306543ECAC
2023-12-14 23:02:07  Found disk4s1 41504653-0000-11AA-AA11-00306543ECAC
2023-12-14 23:02:07  Found disk4s1 41504653-0000-11AA-AA11-00306543ECAC
2023-12-14 23:02:07  Found disk4s1 41504653-0000-11AA-AA11-00306543ECAC
2023-12-14 23:02:07  Found disk4s1 41504653-0000-11AA-AA11-00306543ECAC
2023-12-14 23:02:07  Attempting to mount APFS volume from disk4s1
2023-12-14 23:02:08  Found disk4s1 41504653-0000-11AA-AA11-00306543ECAC
2023-12-14 23:02:08  Mounted disk4s1 at '/Volumes/Backups of admin’s Virtual Machine
2023-12-14 23:02:09  Failed to read capabilities for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Operation not permitted
2023-12-14 23:02:09  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x6000026be8b0 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:02:09  Limiting logging for limit: aFewTimes key: "//timemachine@timemachine._smb._tcp.local./TimeMachine"
2023-12-14 23:02:09  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x60000057fb10 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:02:09  Limiting logging for limit: aFewTimes key: "//timemachine@timemachine._smb._tcp.local./TimeMachine"
2023-12-14 23:02:11  Mountpoint '/Volumes/Backups of admin’s Virtual Machine' is still valid
2023-12-14 23:02:11  Mountpoint '/Volumes/Backups of admin’s Virtual Machine' is still valid
2023-12-14 23:02:11  '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/admin’s Virtual Machine.sparsebundle' mounted at '/Volumes/Backups of admin’s Virtual Machine'
2023-12-14 23:02:11  Mountpoint '/Volumes/Backups of admin’s Virtual Machine' is still valid
2023-12-14 23:02:11  Starting session for '/System/Volumes/Data/Volumes/Backups of admin’s Virtual Machine' with options: 4
2023-12-14 23:02:11  Accepting a new connection from 'Spotlight-mds' for service com.apple.backupd.session.xpc
2023-12-14 23:02:11  Session requested for Time Machine volume '/System/Volumes/Data/Volumes/Backups of admin’s Virtual Machine'
2023-12-14 23:02:11  Session prepared 0 backups (0 cached dates) for /System/Volumes/Data/Volumes/Backups of admin’s Virtual Machine (TMStructureType(_rawValue: APFSMachineStore))
2023-12-14 23:02:11  Prepared 0 backups!
2023-12-14 23:02:11  Limiting logging for limit: aFewTimes key: "Accepting a new connection from 'Spotlight-mds' for service com.apple.backupd.session.xpc"
2023-12-14 23:02:11  Backup list requested for machine store: /System/Volumes/Data/Volumes/Backups of admin’s Virtual Machine
2023-12-14 23:02:12  Checking identity of target volume '/Volumes/Backups of admin’s Virtual Machine'
2023-12-14 23:02:12  Mountpoint '/Volumes/Backups of admin’s Virtual Machine' is still valid
2023-12-14 23:02:12  Set quota for '/Volumes/Backups of admin’s Virtual Machine' to none
2023-12-14 23:02:13  Mountpoint '/Volumes/Backups of admin’s Virtual Machine' is still valid
2023-12-14 23:02:13  Backing up 1 volumes to Backups of admin’s Virtual Machine (/dev/disk4s1,TMBackupOptions(rawValue: 0)): /Volumes/Backups of admin’s Virtual Machine
2023-12-14 23:02:13  Checking destination IO performance at "/Volumes/Backups of admin’s Virtual Machine"
2023-12-14 23:02:13  Wrote 1 50 MB file at 1009.63 MB/s to "/Volumes/Backups of admin’s Virtual Machine" in 0.050 seconds
2023-12-14 23:02:14  Failed destination write test with error Error Domain=NSPOSIXErrorDomain Code=60 "Operation timed out". Wrote 1.7 MB across 420 files.
2023-12-14 23:02:14  Found matching machine store '/Volumes/Backups of admin’s Virtual Machine' for computer named 'admin’s Virtual Machine', no machine store inheritance needed
2023-12-14 23:02:14  Mountpoint '/Volumes/Backups of admin’s Virtual Machine' is still valid
2023-12-14 23:02:15  Running with Spotlight inline indexing disabled.
2023-12-14 23:02:16  fs_snapshot_list failed: Operation not supported
2023-12-14 23:02:16  fs_snapshot_list failed: Operation not supported
2023-12-14 23:02:16  fs_snapshot_list failed: Operation not supported
2023-12-14 23:02:16  Starting age based thinning of Time Machine local snapshots on disk '/System/Volumes/Data'
2023-12-14 23:02:16  Starting age based thinning of Time Machine local snapshots on disk '/Volumes/Backups of admin’s Virtual Machine'
2023-12-14 23:02:16  Starting age based thinning of Time Machine local snapshots on disk '/'
2023-12-14 23:02:24  Created Time Machine local snapshot with name 'com.apple.TimeMachine.2023-12-14-230216.local' on disk '/System/Volumes/Data'
2023-12-14 23:02:24  Declared stable snapshot: com.apple.TimeMachine.2023-12-14-230216.local
2023-12-14 23:02:24  Mounted stable snapshot: com.apple.TimeMachine.2023-12-14-230216.local at path: /Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/admin’s Virtual Machine/2023-12-14-230216/Data source: Data
2023-12-14 23:02:24  Mounted reference snapshot: com.apple.TimeMachine.2023-12-14-223004.local at path: /Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/admin’s Virtual Machine/2023-12-14-223004/Data source: Data
2023-12-14 23:02:24  Checking if file provider is ready for backup at path file:///Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/admin%E2%80%99s%20Virtual%20Machine/2023-12-14-230216/Data/
2023-12-14 23:02:24  File provider reported file:///Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/admin%E2%80%99s%20Virtual%20Machine/2023-12-14-230216/Data/ is ready for backup
2023-12-14 23:02:25  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x60000051e0d0 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:02:25  Failed to read capabilities for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Operation not permitted
2023-12-14 23:02:25  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x6000026b2910 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:02:25  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x600000571bc0 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:02:25  Failed to read capabilities for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Operation not permitted
2023-12-14 23:02:25  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x6000026d8420 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:02:25  Found existing volume store '/Volumes/Backups of admin’s Virtual Machine/2023-12-14-223100.previous/Data' for disk '/System/Volumes/Data'
2023-12-14 23:02:25  Collecting events on 1 volumes...
2023-12-14 23:02:25  Got new file ID based proportions for 1 devices
2023-12-14 23:02:25  Using FSEvents for source: "Data" (device: /dev/disk2s5 mount: '/System/Volumes/Data' fsUUID: 6EC76615-318E-4027-A33C-B6D5F5D12DAB eventDBUUID: 32F1C3C5-7ED6-4EA3-81C1-23CDB1CD9C6A) fsEvents(startEventID: 62587, startDate: 2023-12-14 14:29:57 +0000, previousCloneCacheURL: Optional(file:///Volumes/Backups%20of%20admin%E2%80%99s%20Virtual%20Machine/2023-12-14-223100.previous/.6EC76615-318E-4027-A33C-B6D5F5D12DAB.clonedb))
2023-12-14 23:02:26  Resolving events for "/System/Volumes/Data" concurrently
2023-12-14 23:02:26  Buffered 4340 events.
2023-12-14 23:02:26  Doing secondary scan of nodes needing deep traversal (Data)
2023-12-14 23:02:26  Finished secondary scan of Data
2023-12-14 23:02:26  EventDatabase: node count: 405 events processed: 579 max depth: 12 strategy: FSEvents
2023-12-14 23:02:26  Finished collecting events from volume "Data"
2023-12-14 23:02:26  Finishing "Data":  4340 (100%) of 100% - 3 viewpoints Average Speed: 0.00 events/s
2023-12-14 23:02:26  Finished collecting events on 1 volumes...
2023-12-14 23:02:27  Estimated a total of 241293 files (8.28 GB) will be in backup of 'Data'
2023-12-14 23:02:27  Estimated full backup will contain 241293 files (8.28 GB) from all sources
2023-12-14 23:02:27  Disabling dataless directory preservation.
2023-12-14 23:02:27  Starting propagation look-ahead for "Data"
2023-12-14 23:02:27  Copying from source ""Data" (device: /dev/disk2s5 mount: '/System/Volumes/Data' fsUUID: 6EC76615-318E-4027-A33C-B6D5F5D12DAB eventDBUUID: 32F1C3C5-7ED6-4EA3-81C1-23CDB1CD9C6A)" to "/Volumes/Backups of admin’s Virtual Machine/2023-12-14-230224.inprogress"
2023-12-14 23:02:27  Changed from "none" to "deviceIsUnlocked"
2023-12-14 23:02:29  Found 53.9 MB (244 items) of content to propagate down to depth 3 under "/Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/admin’s Virtual Machine/2023-12-14-230216/Data"
2023-12-14 23:02:36  Failed to read capabilities for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Operation not permitted
2023-12-14 23:02:36  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x6000026db1b0 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:02:36  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x60000056be40 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:02:37  Calculated first time remaining estimate. Waiting for more samples...
2023-12-14 23:02:47  Reached a consensus to calculate time remaining. Yeas: ByPhysicalSize,ByEventPath
2023-12-14 23:02:48  .•••••     .
2023-12-14 23:02:49  Calculated first time remaining estimate. Waiting for more samples...
2023-12-14 23:02:57  Spotlight finished indexing for '/Volumes/Backups of admin’s Virtual Machine'
2023-12-14 23:03:00  Finished copying items for "Data" (device: /dev/disk2s5 mount: '/System/Volumes/Data' fsUUID: 6EC76615-318E-4027-A33C-B6D5F5D12DAB eventDBUUID: 32F1C3C5-7ED6-4EA3-81C1-23CDB1CD9C6A)
2023-12-14 23:03:00  Finished copying from volume "Data"
2023-12-14 23:03:02  .•+****•+**•.
2023-12-14 23:03:02  Time Estimates Evaluation
2023-12-14 23:03:02  Finalizing completed backup
2023-12-14 23:03:03  Marked as reference snapshot: com.apple.TimeMachine.2023-12-14-230216.local
2023-12-14 23:03:03  Finished finalizing completed backup
2023-12-14 23:03:14  Unmounted '/Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/admin’s Virtual Machine/2023-12-14-223004/Data'
2023-12-14 23:03:14  Unmounted local snapshot: com.apple.TimeMachine.2023-12-14-223004.local at path: /Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/admin’s Virtual Machine/2023-12-14-223004/Data source: Data
2023-12-14 23:03:14  Unmounted '/Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/admin’s Virtual Machine/2023-12-14-230216/Data'
2023-12-14 23:03:14  Unmounted local snapshot: com.apple.TimeMachine.2023-12-14-230216.local at path: /Volumes/com.apple.TimeMachine.localsnapshots/Backups.backupdb/admin’s Virtual Machine/2023-12-14-230216/Data source: Data
2023-12-14 23:03:14  Found incomplete backups ready for deletion: (
2023-12-14 23:03:14  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x60000056e1f0 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:03:14  Failed to read capabilities for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Operation not permitted
2023-12-14 23:03:14  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x6000026b2af0 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:03:15  Deleted incomplete backup '/Volumes/Backups of admin’s Virtual Machine/2023-12-14-223100.previous'
2023-12-14 23:03:15  Creating APFS snapshot com.apple.TimeMachine.2023-12-14-230302.backup
2023-12-14 23:03:15  Failed to read capabilities for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Operation not permitted
2023-12-14 23:03:15  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x6000026b1b30 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:03:15  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x600000590fc0 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:03:16  3 backups: 2023-12-14-211523 to 2023-12-14-230302 (2hr,1d) MΔ: 2.02 GB
2023-12-14 23:03:16  Completed backup: 2023-12-14-230302.backup
2023-12-14 23:03:16  Mountpoint '/Volumes/Backups of admin’s Virtual Machine' is still valid
2023-12-14 23:03:16  Mountpoint '/Volumes/Backups of admin’s Virtual Machine' is still valid
2023-12-14 23:03:18  Analytics send failed.
2023-12-14 23:03:18  Found disk4s1 41504653-0000-11AA-AA11-00306543ECAC
2023-12-14 23:03:18  Backup succeeded
2023-12-14 23:03:18  Analytics send failed.
2023-12-14 23:03:21  Mounted '/Volumes/.timemachine/E5C24850-AC3A-4ADB-9B11-BA1B1965ED05/2023-12-14-211523.backup/2023-12-14-211523.backup'
2023-12-14 23:03:21  Failed to read capabilities for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Operation not permitted
2023-12-14 23:03:21  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x6000026c76f0 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
2023-12-14 23:03:21  Failed to get resource value 'NSURLVolumeURLForRemountingKey' for '/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine', error: Error Domain=NSCocoaErrorDomain Code=257 "The file “TimeMachine” couldn’t be opened because you don’t have permission to view it." UserInfo={NSURL=file:///Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine/, NSFilePath=/Volumes/.timemachine/timemachine._smb._tcp.local./E4EC8725-B3E7-4F16-ABF0-034582592279/TimeMachine, NSUnderlyingError=0x600000572970 {Error Domain=NSPOSIXErrorDomain Code=13 "Permission denied"}}
mbentley commented 7 months ago

Try setting the environment variable for SMB_VFS_OBJECTS to: SMB_VFS_OBJECTS="fruit streams_xattr" and see if that resolves the issue for you. It started working for me after I did that.