[Bug 1204351] New: Samba errors with new time machine creation

17 Oct 2022

      http://bugzilla.opensuse.org/show_bug.cgi?id=1204351

            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

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug 1204351] New: Samba errors with new time machine creation

bugzilla_noreply＠suse.com