Bug 13329 - getxattr("...."user.org.netatalk.Metadata", .... ) = -1 ENODATA & weird logging behaviour
getxattr("...."user.org.netatalk.Metadata", .... ) = -1 ENODATA & weird logg...
Status: RESOLVED WORKSFORME
Product: Samba 4.1 and newer
Classification: Unclassified
Component: VFS Modules
unspecified
All All
: P5 normal
: ---
Assigned To: Samba QA Contact
Samba QA Contact
:
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2018-03-13 18:52 UTC by roland
Modified: 2018-10-05 10:26 UTC (History)
0 users

See Also:


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description roland 2018-03-13 18:52:11 UTC
For testing if "production ready" i'm implementing Multiple-User Time-Machine Backup with samba 4.8.0rc4 and zfsonlinux v0.7.6 and it's looking promising so far.

Taking a closer look, i see quite high smbd cpu load on initial time machine run and with that, a lot of Metadata lookup misses, even after setting xattr=sa and dnodesize=auto in zfs (as recommended, see below excerpt from zfs manpage)

furthermore, even with "log level = 0" i'm getting tons of entries in log.smbd, which seem to relate to time-machine.

Maybe someone with deeper knowledge wants to have a look to judge if this is normal/expected behaviour or not. 

for me it looks very weird and i also wonder about the high smbd load. maybe it's because samba has tom maintain a lot of locked files, as timemachine sparsebundle image conists of multiple "bands" files from which many are opened and locked in parallel ?

[root@tmbackup02]# top
top - 15:21:27 up 6 days,  5:04,  3 users,  load average: 2,33, 2,62, 3,12
Tasks: 270 total,   2 running, 268 sleeping,   0 stopped,   0 zombie
%Cpu(s): 28,8 us, 12,1 sy,  0,0 ni, 53,0 id,  4,5 wa,  0,0 hi,  1,5 si,  0,0 st
KiB Mem :  8010504 total,   998244 free,  3887396 used,  3124864 buff/cache
KiB Swap:  1679356 total,  1679356 free,        0 used.  3204556 avail Mem

  PID USER      PR  NI    VIRT    RES    SHR S  %CPU %MEM     TIME+ COMMAND
  987 tm3       20   0  498392  21000  14928 S  50,0  0,3   1:56.43 smbd
23903 tm1       20   0  501664  38596  20788 R  50,0  0,5  26:50.07 smbd
18531 tm7       20   0  493956  29636  19536 S  37,5  0,4  10:56.84 smbd


smb.conf

[global]
server role = standalone server
server string = virtual personal TimeCapsule
os level = 30
preferred master = yes
workgroup = workgroup
netbios name = tmbackup02
passdb backend = tdbsam
security = user
durable handles = yes
fruit:aapl = yes
fruit:model = MacSamba
ea support = yes

[tm1]
path = /timemachinepool/tm1
comment = Time Machine Backup Disk tm1
valid users = tm1
browsable = yes
writable = yes
create mode = 0600
directory mode = 0700
kernel oplocks = no
kernel share modes = no
posix locking = no
vfs objects = catia fruit streams_xattr
fruit:time machine = yes

<snip>


man zfs

dnodesize=legacy|auto|1k|2k|4k|8k|16k

       Specifies a compatibility mode or literal value for the size of dnodes in the file system. The default value is legacy.  
       Setting this property to a value other than legacy requires the large_dnode pool feature to be enabled.

       Consider setting dnodesize to auto if the dataset uses the xattr=sa property setting and the workload makes heavy use of extended 
       attributes. This may be applicable to SELinux-enabled systems, Lustre servers, and Samba servers,
       for example. Literal values are supported for cases where the optimal size is known in advance and for performance testing.


xattr=on|off|sa

       Controls whether extended attributes are enabled for this file system. Two styles of extended attributes are supported either 
       directory based or system attribute based.

       The default value of on enables directory based extended attributes. This style of extended attribute imposes no practical 
       limit on either the size or      number of attributes which can be set on a file. Although under Linux the getxattr(2) and 
	   setxattr(2) system calls limit the maximum size to 64K. This is the most compatible style of extended attribute and is supported 
	   by all OpenZFS implementations.

       System attribute based xattrs can be enabled by setting the value to sa.  The key advantage of this type of xattr is improved performance.
	   Storing extended attributes as system attributes significantly decreases the amount of disk IO required. Up to 64K of data may be stored 
	   per-file in the space reserved for system attributes. If there is not enough space available for an extended attribute then it will be 
	   automatically written as a directory based xattr. System attribute based extended attributes are not accessible on platforms which do not 
	   support the xattr=sa feature.

       The use of system attribute based xattrs is strongly encouraged for users of SELinux or posix ACLs. Both of these features heavily rely of 
	   extended attributes and benefit significantly from the reduced access time
	   
       The values on and off are equivalent to the xattr and noxattr mount options.
	   
	   
	   

[root@tmbackup02 bands]# strace -p 23903 2>&1 |grep ENO
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbc980ba0, 402) = -1 ENODATA (No data available)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbc981ff0, 402) = -1 ENODATA (No data available)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbcc5b710, 402) = -1 ENODATA (No data available)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbcc5bcc0, 402) = -1 ENODATA (No data available)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbc980dd0, 402) = -1 ENODATA (No data available)
quotactl(Q_GETQUOTA|USRQUOTA, "timemachinepool/tm1", 1041, 0x7ffee36bcc90) = -1 ENOENT (No such file or directory)
quotactl(Q_GETQUOTA|GRPQUOTA, "timemachinepool/tm1", 0, 0x7ffee36bcc90) = -1 ENOENT (No such file or directory)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbccac610, 402) = -1 ENODATA (No data available)
getxattr("/timemachinepool/tm1", "user.org.netatalk.Metadata", 0x55bfbccac610, 402) = -1 ENODATA (No data available)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbccac980, 402) = -1 ENODATA (No data available)
getxattr("/timemachinepool/tm1", "user.org.netatalk.Metadata", 0x55bfbcc5b710, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbc97e4e0, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbc97f440, 402) = -1 ENODATA (No data available)
open("Rolands-MacBook-Pro (7).sparsebundle/._bands", O_RDWR) = -1 ENOENT (No such file or directory)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbcb3eeb0, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbccac980, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbc97e3e0, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbc97fa20, 402) = -1 ENODATA (No data available)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbccac980, 402) = -1 ENODATA (No data available)
getxattr("/timemachinepool/tm1", "user.org.netatalk.Metadata", 0x55bfbccac980, 402) = -1 ENODATA (No data available)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbcb3e200, 402) = -1 ENODATA (No data available)
getxattr("/timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbcb3e200, 402) = -1 ENODATA (No data available)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbcb3e200, 402) = -1 ENODATA (No data available)
getxattr("/timemachinepool/tm1", "user.org.netatalk.Metadata", 0x55bfbcc5b710, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands/17ae", "user.org.netatalk.Metadata", 0x55bfbc97e4e0, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands/17ae", "user.org.netatalk.Metadata", 0x55bfbc97f440, 402) = -1 ENODATA (No data available)
open("Rolands-MacBook-Pro (7).sparsebundle/bands/._17ae", O_RDWR) = -1 ENOENT (No such file or directory)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands/17ae", "user.org.netatalk.Metadata", 0x55bfbcfa3800, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands/17ae", "user.org.netatalk.Metadata", 0x55bfbcfa3eb0, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbcfa3ae0, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbcfa3800, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbc97e3e0, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbc97fa20, 402) = -1 ENODATA (No data available)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbccb7bb0, 402) = -1 ENODATA (No data available)
getxattr("/timemachinepool/tm1", "user.org.netatalk.Metadata", 0x55bfbccb7bb0, 402) = -1 ENODATA (No data available)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbcc5b710, 402) = -1 ENODATA (No data available)
getxattr("/timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbcc5b710, 402) = -1 ENODATA (No data available)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbcc5b710, 402) = -1 ENODATA (No data available)
getxattr("/timemachinepool/tm1", "user.org.netatalk.Metadata", 0x55bfbcc5b710, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands/17af", "user.org.netatalk.Metadata", 0x55bfbc97e4e0, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands/17af", "user.org.netatalk.Metadata", 0x55bfbc97f440, 402) = -1 ENODATA (No data available)
open("Rolands-MacBook-Pro (7).sparsebundle/bands/._17af", O_RDWR) = -1 ENOENT (No such file or directory)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands/17af", "user.org.netatalk.Metadata", 0x55bfbcb3eeb0, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands/17af", "user.org.netatalk.Metadata", 0x55bfbccacbb0, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbcb3eeb0, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbccacbb0, 402) = -1 ENODATA (No data available)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbc97e280, 402) = -1 ENODATA (No data available)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbc97f6d0, 402) = -1 ENODATA (No data available)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbccac6c0, 402) = -1 ENODATA (No data available)
getxattr("/timemachinepool/tm1", "user.org.netatalk.Metadata", 0x55bfbccac6c0, 402) = -1 ENODATA (No data available)
open("./._Rolands-MacBook-Pro (7).sparsebundle", O_RDWR) = -1 ENOENT (No such file or directory)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbc97e3e0, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbc97fa20, 402) = -1 ENODATA (No data available)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbccac980, 402) = -1 ENODATA (No data available)
getxattr("/timemachinepool/tm1", "user.org.netatalk.Metadata", 0x55bfbccac980, 402) = -1 ENODATA (No data available)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbcb3e200, 402) = -1 ENODATA (No data available)
getxattr("/timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbcb3e200, 402) = -1 ENODATA (No data available)
getxattr(".", "user.org.netatalk.Metadata", 0x55bfbcb3e200, 402) = -1 ENODATA (No data available)
getxattr("/timemachinepool/tm1", "user.org.netatalk.Metadata", 0x55bfbcc5b710, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands/1422", "user.org.netatalk.Metadata", 0x55bfbc97e4e0, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands/1422", "user.org.netatalk.Metadata", 0x55bfbc97f440, 402) = -1 ENODATA (No data available)
open("Rolands-MacBook-Pro (7).sparsebundle/bands/._1422", O_RDWR) = -1 ENOENT (No such file or directory)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands/1422", "user.org.netatalk.Metadata", 0x55bfbcfa3800, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands/1422", "user.org.netatalk.Metadata", 0x55bfbcfa3eb0, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbcfa3ae0, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbcfa3800, 402) = -1 ENODATA (No data available)
getxattr("Rolands-MacBook-Pro (7).sparsebundle/bands", "user.org.netatalk.Metadata", 0x55bfbc97e3e0, 402) = -1 ENODATA (No data available)



log.smbd

2018/03/13 19:41:23.073264, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool
[2018/03/13 19:41:23.073286, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1
[2018/03/13 19:41:23.073308, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle
[2018/03/13 19:41:23.073331, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle/bands
[2018/03/13 19:41:23.073690, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:400(messaging_recv_cb)
  messaging_recv_cb: Received message 0x31b len 93 (num_fds:0) from 24620
[2018/03/13 19:41:23.073728, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:683(notifyd_trigger)
  notifyd_trigger: Got trigger_msg action=3, filter=0, path=/timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle/bands/4d65
[2018/03/13 19:41:23.073753, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool
[2018/03/13 19:41:23.073776, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1
[2018/03/13 19:41:23.073798, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle
[2018/03/13 19:41:23.073820, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle/bands
[2018/03/13 19:41:23.074840, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:400(messaging_recv_cb)
  messaging_recv_cb: Received message 0x31b len 92 (num_fds:0) from 24620
[2018/03/13 19:41:23.074877, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:683(notifyd_trigger)
  notifyd_trigger: Got trigger_msg action=3, filter=0, path=/timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle/bands/46f
[2018/03/13 19:41:23.074901, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool
[2018/03/13 19:41:23.074924, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1
[2018/03/13 19:41:23.074952, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle
[2018/03/13 19:41:23.074975, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle/bands
[2018/03/13 19:41:23.075297, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:400(messaging_recv_cb)
  messaging_recv_cb: Received message 0x31b len 92 (num_fds:0) from 24620
[2018/03/13 19:41:23.075328, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:683(notifyd_trigger)
  notifyd_trigger: Got trigger_msg action=3, filter=0, path=/timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle/bands/963
[2018/03/13 19:41:23.075352, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool
[2018/03/13 19:41:23.075374, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1
[2018/03/13 19:41:23.075396, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle
[2018/03/13 19:41:23.075419, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle/bands
[2018/03/13 19:41:23.075749, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:400(messaging_recv_cb)
  messaging_recv_cb: Received message 0x31b len 92 (num_fds:0) from 24620
[2018/03/13 19:41:23.075786, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:683(notifyd_trigger)
  notifyd_trigger: Got trigger_msg action=3, filter=0, path=/timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle/bands/470
[2018/03/13 19:41:23.075811, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool
[2018/03/13 19:41:23.075834, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1
[2018/03/13 19:41:23.075856, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle
[2018/03/13 19:41:23.075878, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle/bands
[2018/03/13 19:41:23.076170, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:400(messaging_recv_cb)
  messaging_recv_cb: Received message 0x31b len 91 (num_fds:0) from 24620
[2018/03/13 19:41:23.076204, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:683(notifyd_trigger)
  notifyd_trigger: Got trigger_msg action=3, filter=0, path=/timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle/bands/49
[2018/03/13 19:41:23.076229, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool
[2018/03/13 19:41:23.076251, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1
[2018/03/13 19:41:23.076273, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle
[2018/03/13 19:41:23.076301, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle/bands
[2018/03/13 19:41:23.076664, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:400(messaging_recv_cb)
  messaging_recv_cb: Received message 0x31b len 92 (num_fds:0) from 24620
[2018/03/13 19:41:23.076702, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:683(notifyd_trigger)
  notifyd_trigger: Got trigger_msg action=3, filter=0, path=/timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle/bands/1c8
[2018/03/13 19:41:23.076726, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool
[2018/03/13 19:41:23.076749, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1
[2018/03/13 19:41:23.076771, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
  notifyd_trigger: Trying path /timemachinepool/tm1/Rolands-MacBook-Pro (7).sparsebundle
[2018/03/13 19:41:23.076794, 10, pid=20006, effective(0, 0), real(0, 0)] ../source3/smbd/notifyd/notifyd.c:700(notifyd_trigger)
Comment 1 roland 2018-03-14 11:01:35 UTC
regarding the logging issue, i must have been wrong, logging doesn't happen anymore after a complete system boot
Comment 2 Ralph Böhme 2018-10-05 10:26:50 UTC
Sounds like expected behaviour.

With the logging being resolved I guess we can close this issue. Feel free to reopen in case you want to follow up.