[Samba] Extended attributes not working on mac

Fri Jun 5 11:37:56 UTC 2020

as i can see from the log files it is reading 60 bytes from the extended attributes but not from any other than DosStream.AFP_AfpInfo.
All of the .com.apple.* attributes, especially DosStream.com.apple.metadata:_kMDItemUserTags are missing when listing the directory. 
And again - it tries to access a dotbar file which cannot exist with these settings.

BTW, accessing kMDItemFinderComment works without any issue - i can write and read my comments from finder.

[2020/06/05 11:07:14.015955, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1193(check_reduced_name)
  check_reduced_name: check_reduced_name [.] [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.016078, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1256(check_reduced_name)
  check_reduced_name realpath [.] -> [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.016101,  5, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1368(check_reduced_name)
  check_reduced_name: . reduced to /home/ssd-data/buchhaltung
[2020/06/05 11:07:14.016353, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1193(check_reduced_name)
  check_reduced_name: check_reduced_name [.] [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.016384, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1256(check_reduced_name)
  check_reduced_name realpath [.] -> [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.016401,  5, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1368(check_reduced_name)
  check_reduced_name: . reduced to /home/ssd-data/buchhaltung
[2020/06/05 11:07:14.016423, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/modules/vfs_streams_xattr.c:388(streams_xattr_open)
  streams_xattr_open called for . with flags 0x30000
[2020/06/05 11:07:14.016581, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1193(check_reduced_name)
  check_reduced_name: check_reduced_name [Ohne Titel 15.txt] [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.016623, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1256(check_reduced_name)
  check_reduced_name realpath [Ohne Titel 15.txt] -> [/home/ssd-data/buchhaltung/Ohne Titel 15.txt]
[2020/06/05 11:07:14.016641,  5, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1368(check_reduced_name)
  check_reduced_name: Ohne Titel 15.txt reduced to /home/ssd-data/buchhaltung/Ohne Titel 15.txt
[2020/06/05 11:07:14.016680,  3] ../../source3/smbd/dir.c:659(dptr_create)
  creating new dirptr 0 for path ., expect_close = 0
[2020/06/05 11:07:14.017260,  3] ../../source3/smbd/dir.c:1224(smbd_dirptr_get_entry)
  smbd_dirptr_get_entry mask=[Ohne Titel 15.txt] found Ohne Titel 15.txt fname=Ohne Titel 15.txt (Ohne Titel 15.txt)
[2020/06/05 11:07:14.017343, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/modules/vfs_streams_xattr.c:127(streams_xattr_get_name)
  xattr_name: user.DosStream.AFP_AfpInfo:$DATA, stream_name: :AFP_AfpInfo
[2020/06/05 11:07:14.017460,  2] ../../source3/smbd/dosmode.c:136(unix_mode)
  unix_mode(Ohne Titel 15.txt) inheriting from .
[2020/06/05 11:07:14.017476,  2] ../../source3/smbd/dosmode.c:162(unix_mode)
  unix_mode(Ohne Titel 15.txt) inherit mode 40777
[2020/06/05 11:07:14.017561,  2] ../../source3/smbd/open.c:1452(open_file)
  martin opened file Ohne Titel 15.txt read=No write=No (numopen=2)
[2020/06/05 11:07:14.017610,  2] ../../source3/smbd/dosmode.c:136(unix_mode)
  unix_mode(Ohne Titel 15.txt:AFP_AfpInfo) inheriting from .
[2020/06/05 11:07:14.017622,  2] ../../source3/smbd/dosmode.c:162(unix_mode)
  unix_mode(Ohne Titel 15.txt:AFP_AfpInfo) inherit mode 40777
[2020/06/05 11:07:14.017667, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1193(check_reduced_name)
  check_reduced_name: check_reduced_name [Ohne Titel 15.txt] [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.017682, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1256(check_reduced_name)
  check_reduced_name realpath [Ohne Titel 15.txt] -> [/home/ssd-data/buchhaltung/Ohne Titel 15.txt]
[2020/06/05 11:07:14.017690,  5, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1368(check_reduced_name)
  check_reduced_name: Ohne Titel 15.txt reduced to /home/ssd-data/buchhaltung/Ohne Titel 15.txt
[2020/06/05 11:07:14.017707, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/modules/vfs_streams_xattr.c:388(streams_xattr_open)
  streams_xattr_open called for Ohne Titel 15.txt:AFP_AfpInfo with flags 0x20000
[2020/06/05 11:07:14.017716, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/modules/vfs_streams_xattr.c:127(streams_xattr_get_name)
  xattr_name: user.DosStream.AFP_AfpInfo:$DATA, stream_name: :AFP_AfpInfo
[2020/06/05 11:07:14.017749, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/modules/vfs_streams_xattr.c:419(streams_xattr_open)
  get_ea_value returned NT_STATUS_OK
[2020/06/05 11:07:14.017774, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/modules/vfs_streams_xattr.c:211(streams_xattr_fstat)
  streams_xattr_fstat: streams_xattr_fstat called for Ohne Titel 15.txt:AFP_AfpInfo
[2020/06/05 11:07:14.017784, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/modules/vfs_streams_xattr.c:127(streams_xattr_get_name)
  xattr_name: user.DosStream.AFP_AfpInfo:$DATA, stream_name: :AFP_AfpInfo
[2020/06/05 11:07:14.017817, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/modules/vfs_streams_xattr.c:248(streams_xattr_fstat)
  sbuf->st_ex_size = 60
[2020/06/05 11:07:14.017830,  2] ../../source3/smbd/open.c:1452(open_file)
  martin opened file Ohne Titel 15.txt:AFP_AfpInfo read=Yes write=No (numopen=3)
[2020/06/05 11:07:14.017863, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/modules/vfs_streams_xattr.c:1010(streams_xattr_pread)
  streams_xattr_pread: offset=0, size=60
[2020/06/05 11:07:14.017899, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/modules/vfs_streams_xattr.c:1040(streams_xattr_pread)
  streams_xattr_pread: get_ea_value returned 60 bytes
[2020/06/05 11:07:14.017919, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/modules/vfs_streams_xattr.c:532(streams_xattr_close)
  streams_xattr_close: streams_xattr_close called [Ohne Titel 15.txt:AFP_AfpInfo] fd [32]
[2020/06/05 11:07:14.017934,  2] ../../source3/smbd/close.c:810(close_normal_file)
  martin closed file Ohne Titel 15.txt:AFP_AfpInfo (numopen=2) NT_STATUS_OK
[2020/06/05 11:07:14.017947,  2] ../../source3/smbd/close.c:810(close_normal_file)
  martin closed file Ohne Titel 15.txt (numopen=1) NT_STATUS_OK
[2020/06/05 11:07:14.018556, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1193(check_reduced_name)
  check_reduced_name: check_reduced_name [.] [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.018578, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1256(check_reduced_name)
  check_reduced_name realpath [.] -> [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.018588,  5, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1368(check_reduced_name)
  check_reduced_name: . reduced to /home/ssd-data/buchhaltung
[2020/06/05 11:07:14.018670, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1193(check_reduced_name)
  check_reduced_name: check_reduced_name [.] [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.018684, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1256(check_reduced_name)
  check_reduced_name realpath [.] -> [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.018691,  5, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1368(check_reduced_name)
  check_reduced_name: . reduced to /home/ssd-data/buchhaltung
[2020/06/05 11:07:14.018700, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/modules/vfs_streams_xattr.c:388(streams_xattr_open)
  streams_xattr_open called for . with flags 0x30000
[2020/06/05 11:07:14.018756, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1193(check_reduced_name)
  check_reduced_name: check_reduced_name [.] [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.018779, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1256(check_reduced_name)
  check_reduced_name realpath [.] -> [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.018787,  5, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1368(check_reduced_name)
  check_reduced_name: . reduced to /home/ssd-data/buchhaltung
[2020/06/05 11:07:14.018873, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1193(check_reduced_name)
  check_reduced_name: check_reduced_name [._Ohne Titel 15.txt] [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.018892, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1256(check_reduced_name)
  check_reduced_name realpath [._Ohne Titel 15.txt] -> [/home/ssd-data/buchhaltung/._Ohne Titel 15.txt]
[2020/06/05 11:07:14.018901,  5, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1368(check_reduced_name)
  check_reduced_name: ._Ohne Titel 15.txt reduced to /home/ssd-data/buchhaltung/._Ohne Titel 15.txt
[2020/06/05 11:07:14.018911,  3] ../../source3/smbd/dir.c:659(dptr_create)
  creating new dirptr 0 for path ., expect_close = 0
[2020/06/05 11:07:14.018971,  3] ../../source3/smbd/smb2_server.c:3254(smbd_smb2_request_error_ex)
  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:159
[2020/06/05 11:07:14.037353, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1193(check_reduced_name)
  check_reduced_name: check_reduced_name [.] [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.037438, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1256(check_reduced_name)
  check_reduced_name realpath [.] -> [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.037459,  5, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1368(check_reduced_name)
  check_reduced_name: . reduced to /home/ssd-data/buchhaltung
[2020/06/05 11:07:14.037707, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1193(check_reduced_name)
  check_reduced_name: check_reduced_name [.] [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.037737, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1256(check_reduced_name)
  check_reduced_name realpath [.] -> [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.037754,  5, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1368(check_reduced_name)
  check_reduced_name: . reduced to /home/ssd-data/buchhaltung
[2020/06/05 11:07:14.037776, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/modules/vfs_streams_xattr.c:388(streams_xattr_open)
  streams_xattr_open called for . with flags 0x30000
[2020/06/05 11:07:14.037933, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1193(check_reduced_name)
  check_reduced_name: check_reduced_name [.] [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.037965, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1256(check_reduced_name)
  check_reduced_name realpath [.] -> [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.037983,  5, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1368(check_reduced_name)
  check_reduced_name: . reduced to /home/ssd-data/buchhaltung
[2020/06/05 11:07:14.038166, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1193(check_reduced_name)
  check_reduced_name: check_reduced_name [.git] [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.038209, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1256(check_reduced_name)
  check_reduced_name realpath [.git] -> [/home/ssd-data/buchhaltung/.git]
[2020/06/05 11:07:14.038245,  5, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1368(check_reduced_name)
  check_reduced_name: .git reduced to /home/ssd-data/buchhaltung/.git
[2020/06/05 11:07:14.038258,  3] ../../source3/smbd/dir.c:659(dptr_create)
  creating new dirptr 0 for path ., expect_close = 0
[2020/06/05 11:07:14.038341,  3] ../../source3/smbd/smb2_server.c:3254(smbd_smb2_request_error_ex)
  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:159
[2020/06/05 11:07:14.039307, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1193(check_reduced_name)
  check_reduced_name: check_reduced_name [.] [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.039330, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1256(check_reduced_name)
  check_reduced_name realpath [.] -> [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.039340,  5, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1368(check_reduced_name)
  check_reduced_name: . reduced to /home/ssd-data/buchhaltung
[2020/06/05 11:07:14.039421, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1193(check_reduced_name)
  check_reduced_name: check_reduced_name [.] [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.039435, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1256(check_reduced_name)
  check_reduced_name realpath [.] -> [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.039443,  5, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1368(check_reduced_name)
  check_reduced_name: . reduced to /home/ssd-data/buchhaltung
[2020/06/05 11:07:14.039451, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/modules/vfs_streams_xattr.c:388(streams_xattr_open)
  streams_xattr_open called for . with flags 0x30000
[2020/06/05 11:07:14.039505, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1193(check_reduced_name)
  check_reduced_name: check_reduced_name [.] [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.039519, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1256(check_reduced_name)
  check_reduced_name realpath [.] -> [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.039526,  5, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1368(check_reduced_name)
  check_reduced_name: . reduced to /home/ssd-data/buchhaltung
[2020/06/05 11:07:14.039578, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1193(check_reduced_name)
  check_reduced_name: check_reduced_name [HEAD] [/home/ssd-data/buchhaltung]
[2020/06/05 11:07:14.039596, 10, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1256(check_reduced_name)
  check_reduced_name realpath [HEAD] -> [/home/ssd-data/buchhaltung/HEAD]
[2020/06/05 11:07:14.039604,  5, pid=51807, effective(1000, 1000), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1368(check_reduced_name)
  check_reduced_name: HEAD reduced to /home/ssd-data/buchhaltung/HEAD
[2020/06/05 11:07:14.039614,  3] ../../source3/smbd/dir.c:659(dptr_create)
  creating new dirptr 0 for path ., expect_close = 0
[2020/06/05 11:07:14.039671,  3] ../../source3/smbd/smb2_server.c:3254(smbd_smb2_request_error_ex)
  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:159

> Am 05.06.2020 um 11:59 schrieb Ralph Boehme <slow at samba.org>:
> 
> Am 6/5/20 um 11:14 AM schrieb Martin Steiner / 3MF GmbH:
>> these lines were an option before but have already been marked as comments in my config.
>> same behaviour as before, color tag shows for round about a second and disappears suddenly.
> 
> so it seems the server sets the correctly on the server filesystem, but
> on the way back it fails to read them.
> 
> *scratches head*
> 
> Sorry, no idea what is causing this. You may want to get down and dirty
> and check level 10 logs of a reproducer.
> 
> -slow
> 
> -- 
> Ralph Boehme, Samba Team                https://samba.org/
> Samba Developer, SerNet GmbH   https://sernet.de/en/samba/
> GPG-Fingerprint   FAE2C6088A24252051C559E4AA1E9B7126399E46
> 