Bug ID | 1204351 |
---|---|
Summary | Samba errors with new time machine creation |
Classification | openSUSE |
Product | openSUSE Tumbleweed |
Version | Current |
Hardware | Other |
OS | Other |
Status | NEW |
Severity | Normal |
Priority | P5 - None |
Component | Samba |
Assignee | samba-maintainers@SuSE.de |
Reporter | william.brown@suse.com |
QA Contact | samba-maintainers@SuSE.de |
Found By | --- |
Blocker | --- |
samba-4.17.0+git.257.5f0ed03584a-1.1.x86_64 It appears that time machine starts to fail on this version. Console.app shows: Failed to create '/Volumes/.timemachine/files.blackhats.net.au/62C07BA3-55AD-4170-9D50-9E0A65EE5BEC/timemachine_a/5748074D-E5E5-5804-9C0B-1FEB8E475709.sparsebundle', results: { }, error: 13 Permission denied Backup failed (20: BACKUP_FAILED_DISK_IMAGE_NOT_CREATED) The folder in question is owned by the user timemachine:timemachine, and is *not* a symlink or hardlink. I have tried with and without acl_xattr. The share configuration is: [timemachine_a] comment = Time Machine path = /var/data/backup/timemachine_a browseable = yes write list = timemachine create mask = 0600 directory mask = 0700 vfs objects = catia fruit streams_xattr acl_xattr # Requires external daemon spotlight = no fruit:aapl = yes fruit:time machine = yes # Need to fit maxixe with a 2T nvme # fruit:time machine max size = 2200G # Generally these will improve performance, at the expense of the ability # to perform local parallel access to the fileserver. durable handles = yes kernel oplocks = no kernel share modes = no posix locking = no strict locking = no # File casing case sensitive = true default case = lower preserve case = no short preserve case = no hosts allow = 127.0.0.0/8 172.24.12.0/24 2001:44b8:2155:2c12::/64 hosts deny = 0.0.0.0/0 From the samba server on debug level 3 the follow messages are emitted during an attempted backup. Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_smb2_notify_send: notify change called on ., filter = FILE_NAME|DIR_NAME|ATTRIBUTES|CREATION|SECURITY|STREAM_SIZE|STREAM_WRITE, recursive = 0 Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_create.c:339 Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[5] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_server.c:3324 Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[9] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_server.c:3324 Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_do_qfsinfo: level = 1003 Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found . fname=. (.) Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found .. fname=.. (..) Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle fname=5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle (5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle) Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found untitled folder fname=untitled folder (untitled folder) Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found .ds_store fname=.ds_store (.ds_store) Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[STATUS_NO_MORE_FILES] || at ../../source3/smbd/smb2_query_directory.c:160 Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_create.c:339 Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle/. fname=. (.) Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle/.. fname=.. (..) Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[STATUS_NO_MORE_FILES] || at ../../source3/smbd/smb2_query_directory.c:160 Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle/. fname=. (.) Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle/.. fname=.. (..) Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[STATUS_NO_MORE_FILES] || at ../../source3/smbd/smb2_query_directory.c:160 Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle/. fname=. (.) Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle/.. fname=.. (..) Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[STATUS_NO_MORE_FILES] || at ../../source3/smbd/smb2_query_directory.c:160 Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle/. fname=. (.) Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle/.. fname=.. (..) Oct 17 12:00:34 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[STATUS_NO_MORE_FILES] || at ../../source3/smbd/smb2_query_directory.c:160 Oct 17 12:00:39 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle fname=5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle (5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle) Oct 17 12:00:39 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_create.c:339 Oct 17 12:00:39 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_create.c:339 Oct 17 12:00:44 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_FILE_CLOSED] || at ../../source3/smbd/smb2_server.c:3333 Oct 17 12:00:49 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle fname=5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle (5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle) Oct 17 12:00:49 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_create.c:339 Oct 17 12:00:49 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_create.c:339 Oct 17 12:00:59 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_FILE_CLOSED] || at ../../source3/smbd/smb2_server.c:3333 Oct 17 12:00:59 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle fname=5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle (5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle) Oct 17 12:00:59 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_create.c:339 Oct 17 12:00:59 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_create.c:339 Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Registered MSG_REQ_POOL_USAGE Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Allowed connection from 2001:44b8:2155:2c10::201 (2001:44b8:2155:2c10::201) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: init_oplocks: initializing messages. Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Selected protocol SMB3_11 Oct 17 12:01:04 mion 5916e0f36f1a[4382]: GENSEC backend 'gssapi_spnego' registered Oct 17 12:01:04 mion 5916e0f36f1a[4382]: GENSEC backend 'gssapi_krb5' registered Oct 17 12:01:04 mion 5916e0f36f1a[4382]: GENSEC backend 'gssapi_krb5_sasl' registered Oct 17 12:01:04 mion 5916e0f36f1a[4382]: GENSEC backend 'spnego' registered Oct 17 12:01:04 mion 5916e0f36f1a[4382]: GENSEC backend 'schannel' registered Oct 17 12:01:04 mion 5916e0f36f1a[4382]: GENSEC backend 'ncalrpc_as_system' registered Oct 17 12:01:04 mion 5916e0f36f1a[4382]: GENSEC backend 'sasl-EXTERNAL' registered Oct 17 12:01:04 mion 5916e0f36f1a[4382]: GENSEC backend 'ntlmssp' registered Oct 17 12:01:04 mion 5916e0f36f1a[4382]: GENSEC backend 'ntlmssp_resume_ccache' registered Oct 17 12:01:04 mion 5916e0f36f1a[4382]: GENSEC backend 'http_basic' registered Oct 17 12:01:04 mion 5916e0f36f1a[4382]: GENSEC backend 'http_ntlm' registered Oct 17 12:01:04 mion 5916e0f36f1a[4382]: GENSEC backend 'http_negotiate' registered Oct 17 12:01:04 mion 5916e0f36f1a[4382]: GENSEC backend 'krb5' registered Oct 17 12:01:04 mion 5916e0f36f1a[4382]: GENSEC backend 'fake_gssapi_krb5' registered Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Got NTLMSSP neg_flags=0x62088215 Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Got user=[nobody] domain=[BLACKHATS] workstation=[TOPAZ] len1=24 len2=258 Oct 17 12:01:04 mion 5916e0f36f1a[4382]: check_ntlm_password: Checking password for unmapped user [BLACKHATS]\[nobody]@[TOPAZ] with the new password interface Oct 17 12:01:04 mion 5916e0f36f1a[4382]: check_ntlm_password: mapped user is: [BLACKHATS]\[nobody]@[TOPAZ] Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Forcing Primary Group to 'Domain Users' for nobody Oct 17 12:01:04 mion 5916e0f36f1a[4382]: auth_check_ntlm_password: sam_ignoredomain authentication for user [nobody] succeeded Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Auth: [SMB2,(null)] user [BLACKHATS]\[nobody] at [Mon, 17 Oct 2022 02:01:04.199720 UTC] with [NTLMv2] status [NT_STATUS_OK] workstation [TOPAZ] remote host [ipv6:2001:44b8:2155:2c10::201:22328] became [FILES]\[nobody] [S-1-5-21-3163336751-3919787165-3357624109-501]. local host [ipv6:2001:44b8:2155:2c11::1003:445] Oct 17 12:01:04 mion 5916e0f36f1a[4382]: {"timestamp": "2022-10-17T02:01:04.199773+0000", "type": "Authentication", "Authentication": {"version": {"major": 1, "minor": 2}, "eventId": 4624, "logonId": "0", "logonType": 3, "status": "NT_STATUS_OK", "localAddress": "ipv6:2001:44b8:2155:2c11::1003:445", "remoteAddress": "ipv6:2001:44b8:2155:2c10::201:22328", "serviceDescription": "SMB2", "authDescription": null, "clientDomain": "BLACKHATS", "clientAccount": "nobody", "workstation": "TOPAZ", "becameAccount": "nobody", "becameDomain": "FILES", "becameSid": "S-1-5-21-3163336751-3919787165-3357624109-501", "mappedAccount": "nobody", "mappedDomain": "BLACKHATS", "netlogonComputer": null, "netlogonTrustAccount": null, "netlogonNegotiateFlags": "0x00000000", "netlogonSecureChannelType": 0, "netlogonTrustAccountSid": null, "passwordType": "NTLMv2", "duration": 1350}} Oct 17 12:01:04 mion 5916e0f36f1a[4382]: check_ntlm_password: authentication for user [nobody] -> [nobody] -> [nobody] succeeded Oct 17 12:01:04 mion 5916e0f36f1a[4382]: lp_load_ex: refreshing parameters Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Initialising global parameters Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Processing section "[global]" Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Processing section "[homes]" Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Processing section "[pub]" Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Processing section "[upload]" Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Processing section "[timemachine_a]" Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Processing section "[scans]" Oct 17 12:01:04 mion 5916e0f36f1a[4382]: adding IPC service Oct 17 12:01:04 mion 5916e0f36f1a[4382]: NTLMSSP Sign/Seal - Initialising with flags: Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Got NTLMSSP neg_flags=0x62088215 Oct 17 12:01:04 mion 5916e0f36f1a[4382]: NTLMSSP Sign/Seal - Initialising with flags: Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Got NTLMSSP neg_flags=0x62088215 Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Failed to fetch domain sid for BLACKHATS Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Adding homes service for user 'nobody' using home directory: '/var/lib/nobody' Oct 17 12:01:04 mion 5916e0f36f1a[4382]: adding home's share [nobody] for user 'nobody' at '/var/lib/nobody' Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Allowed connection from 2001:44b8:2155:2c10::201 (2001:44b8:2155:2c10::201) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: make_connection_snum: Connect path is '/tmp' for service [IPC$] Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Initialising default vfs hooks Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Initialising custom vfs hooks from [/[Default VFS]/] Oct 17 12:01:04 mion 5916e0f36f1a[4382]: topaz (ipv6:2001:44b8:2155:2c10::201:22328) signed connect to service IPC$ initially as user nobody (uid=65534, gid=65534) (pid 73) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: get_referred_path: |pub| in dfs path \files.blackhats.net.au\pub is not a dfs root. Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_NOT_FOUND] || at ../../source3/smbd/smb2_ioctl.c:353 Oct 17 12:01:04 mion 5916e0f36f1a[4382]: topaz (ipv6:2001:44b8:2155:2c10::201:22328) closed connection to service IPC$ Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Allowed connection from 2001:44b8:2155:2c10::201 (2001:44b8:2155:2c10::201) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: make_connection_snum: Connect path is '/var/data/pub' for service [pub] Oct 17 12:01:04 mion 5916e0f36f1a[4382]: string_to_sid: SID @blackhats_network_admins is not in a valid format Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Initialising default vfs hooks Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Initialising custom vfs hooks from [/[Default VFS]/] Oct 17 12:01:04 mion 5916e0f36f1a[4382]: string_to_sid: SID @blackhats_network_admins is not in a valid format Oct 17 12:01:04 mion 5916e0f36f1a[4382]: topaz (ipv6:2001:44b8:2155:2c10::201:22328) signed connect to service pub initially as user nobody (uid=65534, gid=65534) (pid 73) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found . fname=. (.) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found .. fname=.. (..) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found movies fname=movies (movies) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found pictures fname=pictures (pictures) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found software fname=software (software) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found anime fname=anime (anime) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found upload fname=upload (upload) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found series fname=series (series) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found .DS_Store fname=.DS_Store (.DS_Store) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found manga fname=manga (manga) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found centos fname=centos (centos) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found conferences fname=conferences (conferences) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found music fname=music (music) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found StepMania fname=StepMania (StepMania) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found ._.DS_Store fname=._.DS_Store (._.DS_Store) Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[STATUS_NO_MORE_FILES] || at ../../source3/smbd/smb2_query_directory.c:160 Oct 17 12:01:04 mion 5916e0f36f1a[4382]: smbd_do_qfsinfo: level = 1003 Oct 17 12:01:04 mion 5916e0f36f1a[4382]: topaz (ipv6:2001:44b8:2155:2c10::201:22328) closed connection to service pub Oct 17 12:01:04 mion 5916e0f36f1a[4382]: Server exit (NT_STATUS_END_OF_FILE) Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_do_qfsinfo: level = 1003 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_do_qfsinfo: level = 1003 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_create.c:339 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[5] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_server.c:3324 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[9] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_server.c:3324 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[5] status[NT_STATUS_NO_SUCH_FILE] || at ../../source3/smbd/smb2_query_directory.c:160 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_create.c:339 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle fname=5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle (5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle) Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle fname=5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle (5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle) Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle fname=5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle (5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle) Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[5] status[NT_STATUS_NO_SUCH_FILE] || at ../../source3/smbd/smb2_query_directory.c:160 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle fname=5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle (5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle) Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle/. fname=. (.) Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle/.. fname=.. (..) Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[STATUS_NO_MORE_FILES] || at ../../source3/smbd/smb2_query_directory.c:160 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_do_setfilepathinfo: 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle (fnum 1592020204) info_level=1013 totdata=1 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_smb2_notify_send: notify change called on ., filter = FILE_NAME|DIR_NAME|ATTRIBUTES|CREATION|SECURITY|STREAM_SIZE|STREAM_WRITE, recursive = 0 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[5] status[NT_STATUS_NO_SUCH_FILE] || at ../../source3/smbd/smb2_query_directory.c:160 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: unpack_nt_owners: owner sid mapped to uid 1464116672 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: unpack_nt_owners: group sid mapped to gid 1464116672 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: set_canon_ace_list: sys_acl_set_file on file [5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle]: (Operation not supported) Oct 17 12:01:09 mion 5916e0f36f1a[4382]: set_nt_acl: chmod 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle. perms = 0700. Oct 17 12:01:09 mion 5916e0f36f1a[4382]: fruit_fset_nt_acl: SMB_VFS_NEXT_FSET_NT_ACL failed5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_smb2_notify_send: notify change called on ., filter = FILE_NAME|DIR_NAME|ATTRIBUTES|CREATION|SECURITY|STREAM_SIZE|STREAM_WRITE, recursive = 0 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_marshall_security_desc: sd_size = 296. Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_smb2_notify_send: notify change called on ., filter = FILE_NAME|DIR_NAME|ATTRIBUTES|CREATION|SECURITY|STREAM_SIZE|STREAM_WRITE, recursive = 0 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: set_canon_ace_list: sys_acl_set_file on file [5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle]: (Operation not supported) Oct 17 12:01:09 mion 5916e0f36f1a[4382]: set_nt_acl: chmod 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle. perms = 0700. Oct 17 12:01:09 mion 5916e0f36f1a[4382]: fruit_fset_nt_acl: SMB_VFS_NEXT_FSET_NT_ACL failed5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[5] status[NT_STATUS_ACCESS_DENIED] || at ../../source3/smbd/smb2_setinfo.c:137 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle fname=5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle (5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle) Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_OBJECT_PATH_NOT_FOUND] || at ../../source3/smbd/smb2_create.c:339 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle/. fname=. (.) Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle/.. fname=.. (..) Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[STATUS_NO_MORE_FILES] || at ../../source3/smbd/smb2_query_directory.c:160 Oct 17 12:01:09 mion 5916e0f36f1a[4382]: smbd_do_setfilepathinfo: 5748074d-e5e5-5804-9c0b-1feb8e475709.sparsebundle (fnum 1815113403) info_level=1013 totdata=1 Oct 17 12:01:10 mion 5916e0f36f1a[4382]: smbd_smb2_notify_send: notify change called on ., filter = FILE_NAME|DIR_NAME|ATTRIBUTES|CREATION|SECURITY|STREAM_SIZE|STREAM_WRITE, recursive = 0 Oct 17 12:01:10 mion 5916e0f36f1a[4382]: smbd_do_qfsinfo: level = 1003 Oct 17 12:01:10 mion 5916e0f36f1a[4382]: maxixe (ipv6:2001:44b8:2155:2c12::133:55406) closed connection to service timemachine_a Oct 17 12:01:10 mion 5916e0f36f1a[4382]: check_reduced_name: Bad access attempt: . is a symlink outside the share path Oct 17 12:01:10 mion 5916e0f36f1a[4382]: conn_rootdir =/var/data/backup/timemachine_a Oct 17 12:01:10 mion 5916e0f36f1a[4382]: resolved_name=/ Oct 17 12:01:10 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found . fname=. (.) Oct 17 12:01:10 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found .. fname=.. (..) Oct 17 12:01:10 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found untitled folder fname=untitled folder (untitled folder) Oct 17 12:01:10 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found .ds_store fname=.ds_store (.ds_store) Oct 17 12:01:10 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[STATUS_NO_MORE_FILES] || at ../../source3/smbd/smb2_query_directory.c:160 Oct 17 12:01:29 mion 5916e0f36f1a[4382]: Could not find child 61 -- ignoring Oct 17 12:01:35 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[untitled folder] found untitled folder fname=untitled folder (untitled folder) Oct 17 12:01:38 mion 5916e0f36f1a[4382]: smbd_do_qfsinfo: level = 1003 Oct 17 12:01:38 mion 5916e0f36f1a[4382]: smbd_do_setfilepathinfo: untitled folder (fnum 4162834489) info_level=1004 totdata=40 Oct 17 12:01:38 mion 5916e0f36f1a[4382]: smbd_smb2_notify_send: notify change called on ., filter = FILE_NAME|DIR_NAME|ATTRIBUTES|CREATION|SECURITY|STREAM_SIZE|STREAM_WRITE, recursive = 0 Oct 17 12:01:38 mion 5916e0f36f1a[4382]: smbd_smb2_notify_send: notify change called on untitled folder, filter = FILE_NAME|DIR_NAME|ATTRIBUTES|CREATION|SECURITY|STREAM_SIZE|STREAM_WRITE, recursive = 0 Oct 17 12:01:38 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[untitled folder] found untitled folder fname=untitled folder (untitled folder) Oct 17 12:01:38 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_create.c:339 Oct 17 12:01:38 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[5] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_server.c:3324 Oct 17 12:01:38 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[9] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_server.c:3324 Oct 17 12:01:38 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found untitled folder/. fname=. (.) Oct 17 12:01:38 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found untitled folder/.. fname=.. (..) Oct 17 12:01:38 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found . fname=. (.) Oct 17 12:01:38 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found .. fname=.. (..) Oct 17 12:01:38 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found untitled folder fname=untitled folder (untitled folder) Oct 17 12:01:38 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found .ds_store fname=.ds_store (.ds_store) Oct 17 12:01:38 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[STATUS_NO_MORE_FILES] || at ../../source3/smbd/smb2_query_directory.c:160 Oct 17 12:01:38 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[STATUS_NO_MORE_FILES] || at ../../source3/smbd/smb2_query_directory.c:160 Oct 17 12:01:38 mion 5916e0f36f1a[4382]: smbd_do_setfilepathinfo: untitled folder (fnum 3893139594) info_level=1004 totdata=40 Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[.ds_store] found .ds_store fname=.ds_store (.ds_store) Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[.ds_store] found .ds_store fname=.ds_store (.ds_store) Oct 17 12:01:51 mion 5916e0f36f1a[4382]: timemachine opened file .ds_store read=No write=No (numopen=18) Oct 17 12:01:51 mion 5916e0f36f1a[4382]: timemachine closed file .ds_store (numopen=17) NT_STATUS_OK Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[.ds_store] found .ds_store fname=.ds_store (.ds_store) Oct 17 12:01:51 mion 5916e0f36f1a[4382]: timemachine opened file .ds_store read=Yes write=Yes (numopen=18) Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_do_setfilepathinfo: .ds_store (fnum 946205098) info_level=1020 totdata=8 Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_smb2_notify_send: notify change called on ., filter = FILE_NAME|DIR_NAME|ATTRIBUTES|CREATION|SECURITY|STREAM_SIZE|STREAM_WRITE, recursive = 0 Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[.ds_store] found .ds_store fname=.ds_store (.ds_store) Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_smb2_notify_send: notify change called on ., filter = FILE_NAME|DIR_NAME|ATTRIBUTES|CREATION|SECURITY|STREAM_SIZE|STREAM_WRITE, recursive = 0 Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smb2: fnum 946205098, file .ds_store, length=4 offset=0 wrote=4 Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_smb2_notify_send: notify change called on ., filter = FILE_NAME|DIR_NAME|ATTRIBUTES|CREATION|SECURITY|STREAM_SIZE|STREAM_WRITE, recursive = 0 Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smb2: fnum 946205098, file .ds_store, length=8192 offset=4 wrote=8192 Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_smb2_notify_send: notify change called on ., filter = FILE_NAME|DIR_NAME|ATTRIBUTES|CREATION|SECURITY|STREAM_SIZE|STREAM_WRITE, recursive = 0 Oct 17 12:01:51 mion 5916e0f36f1a[4382]: timemachine closed file .ds_store (numopen=16) NT_STATUS_OK Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_smb2_notify_send: notify change called on ., filter = FILE_NAME|DIR_NAME|ATTRIBUTES|CREATION|SECURITY|STREAM_SIZE|STREAM_WRITE, recursive = 0 Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[.ds_store] found .ds_store fname=.ds_store (.ds_store) Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[.ds_store] found .ds_store fname=.ds_store (.ds_store) Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_create.c:339 Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[5] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_server.c:3324 Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[9] status[NT_STATUS_OBJECT_NAME_NOT_FOUND] || at ../../source3/smbd/smb2_server.c:3324 Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_do_qfsinfo: level = 1003 Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found . fname=. (.) Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found .. fname=.. (..) Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found untitled folder fname=untitled folder (untitled folder) Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_dirptr_get_entry mask=[*] found .ds_store fname=.ds_store (.ds_store) Oct 17 12:01:51 mion 5916e0f36f1a[4382]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[STATUS_NO_MORE_FILES] || at ../../source3/smbd/smb2_query_directory.c:160