[2022/04/01 19:57:57, 2] ../../source3/lib/interface.c:344(add_interface) added interface enp1s0f0 ip=10.30.2.101 bcast=10.30.255.255 netmask=255.255.0.0 [2022/04/01 19:57:57.963486, 0] ../../source3/smbd/server.c:1734(main) smbd version 4.15.5-TrueNAS started. Copyright Andrew Tridgell and the Samba Team 1992-2021 [2022/04/01 19:57:57.963541, 2] ../../source3/smbd/server.c:1737(main) uid=0 gid=0 euid=0 egid=0 [2022/04/01 19:57:57.963841, 2] ../../source3/lib/tallocmsg.c:84(register_msg_pool_usage) Registered MSG_REQ_POOL_USAGE [2022/04/01 19:57:57.963878, 2] ../../source3/lib/dmallocmsg.c:78(register_dmalloc_msgs) Registered MSG_REQ_DMALLOC_MARK and LOG_CHANGED [2022/04/01 19:57:57.964022, 3] ../../source3/param/loadparm.c:3967(lp_load_ex) lp_load_ex: refreshing parameters [2022/04/01 19:57:57.964088, 3] ../../source3/param/loadparm.c:558(init_globals) Initialising global parameters [2022/04/01 19:57:57.964180, 3] ../../source3/param/loadparm.c:2869(lp_do_section) Processing section "[global]" [2022/04/01 19:57:57.964764, 3] ../../source3/param/loadparm.c:2869(lp_do_section) Processing section "[GLOBAL]" [2022/04/01 19:57:57.964894, 1] ../../lib/param/loadparm.c:1766(lpcfg_do_global_parameter) lpcfg_do_global_parameter: WARNING: The "syslog only" option is deprecated [2022/04/01 19:57:57.965197, 3] ../../source3/param/loadparm.c:1670(lp_add_ipc) adding IPC service [2022/04/01 19:57:57.965541, 2] ../../source3/lib/interface.c:344(add_interface) added interface enp1s0f0 ip=10.30.2.101 bcast=10.30.255.255 netmask=255.255.0.0 [2022/04/01 19:57:57.965607, 3] ../../source3/smbd/server.c:1806(main) loaded services [2022/04/01 19:57:57.967542, 1] ../../source3/profile/profile.c:54(set_profile_level) INFO: Profiling turned OFF from pid 295259 [2022/04/01 19:57:57.967596, 3] ../../source3/smbd/server.c:1825(main) Standard input is not a socket, assuming -D option [2022/04/01 19:57:57.967620, 3] ../../source3/smbd/server.c:1838(main) Becoming a daemon. [2022/04/01 19:57:57.967927, 2] ../../source3/lib/tallocmsg.c:84(register_msg_pool_usage) Registered MSG_REQ_POOL_USAGE [2022/04/01 19:57:57.970087, 3] ../../source3/lib/util_procid.c:53(pid_to_procid) pid_to_procid: messaging_dgm_get_unique failed: No such file or directory [2022/04/01 19:57:57.970720, 2] ../../source3/lib/tallocmsg.c:84(register_msg_pool_usage) Registered MSG_REQ_POOL_USAGE [2022/04/01 19:57:57.972155, 2] ../../source3/lib/tallocmsg.c:84(register_msg_pool_usage) Registered MSG_REQ_POOL_USAGE [2022/04/01 19:57:57.974126, 3] ../../librpc/rpc/dcesrv_core.c:2582(dcerpc_register_ep_server) DCERPC endpoint server 'winreg' registered [2022/04/01 19:57:57.974180, 3] ../../librpc/rpc/dcesrv_core.c:2582(dcerpc_register_ep_server) DCERPC endpoint server 'srvsvc' registered [2022/04/01 19:57:57.974212, 3] ../../librpc/rpc/dcesrv_core.c:2582(dcerpc_register_ep_server) DCERPC endpoint server 'lsarpc' registered [2022/04/01 19:57:57.974271, 3] ../../librpc/rpc/dcesrv_core.c:2582(dcerpc_register_ep_server) DCERPC endpoint server 'samr' registered [2022/04/01 19:57:57.974328, 3] ../../librpc/rpc/dcesrv_core.c:2582(dcerpc_register_ep_server) DCERPC endpoint server 'netdfs' registered [2022/04/01 19:57:57.974360, 3] ../../librpc/rpc/dcesrv_core.c:2582(dcerpc_register_ep_server) DCERPC endpoint server 'dssetup' registered [2022/04/01 19:57:57.974418, 3] ../../librpc/rpc/dcesrv_core.c:2582(dcerpc_register_ep_server) DCERPC endpoint server 'wkssvc' registered [2022/04/01 19:57:57.974448, 3] ../../librpc/rpc/dcesrv_core.c:2582(dcerpc_register_ep_server) DCERPC endpoint server 'svcctl' registered [2022/04/01 19:57:57.974500, 3] ../../librpc/rpc/dcesrv_core.c:2582(dcerpc_register_ep_server) DCERPC endpoint server 'ntsvcs' registered [2022/04/01 19:57:57.974651, 3] ../../librpc/rpc/dcesrv_core.c:2582(dcerpc_register_ep_server) DCERPC endpoint server 'eventlog' registered [2022/04/01 19:57:57.974744, 3] ../../librpc/rpc/dcesrv_core.c:2582(dcerpc_register_ep_server) DCERPC endpoint server 'initshutdown' registered [2022/04/01 19:57:57.974777, 3] ../../source3/rpc_server/rpc_modules.c:64(register_rpc_module) register_rpc_module: Successfully added RPC module 'mdssvc' [2022/04/01 19:57:57.974939, 3] ../../librpc/rpc/dcesrv_core.c:2582(dcerpc_register_ep_server) DCERPC endpoint server 'mdssvc' registered [2022/04/01 19:57:57.975159, 3] ../../source3/rpc_server/svcctl/srv_svcctl_reg.c:565(svcctl_init_winreg) Initialise the svcctl registry keys if needed. [2022/04/01 19:57:57.976791, 3] ../../source3/rpc_server/eventlog/srv_eventlog_reg.c:59(eventlog_init_winreg) Initialise the eventlog registry keys if needed. [2022/04/01 19:57:57.977975, 2] ../../source3/smbd/server.c:1364(smbd_parent_loop) waiting for connections [2022/04/01 19:58:55.153153, 2] ../../source3/lib/tallocmsg.c:84(register_msg_pool_usage) Registered MSG_REQ_POOL_USAGE [2022/04/01 19:58:55.153668, 3] ../../lib/util/access.c:316(allow_access) Allowed connection from 10.30.155.213 (10.30.155.213) [2022/04/01 19:58:55.154452, 3] ../../source3/smbd/oplock.c:1427(init_oplocks) init_oplocks: initializing messages. [2022/04/01 19:58:55.156231, 3] ../../source3/smbd/process.c:1957(process_smb) Transaction 0 of length 159 (0 toread) [2022/04/01 19:58:55.156321, 3] ../../source3/smbd/process.c:1549(switch_message) switch message SMBnegprot (pid 295945) conn 0x0 [2022/04/01 19:58:55.156755, 3] ../../source3/smbd/negprot.c:637(reply_negprot) Requested protocol [PC NETWORK PROGRAM 1.0] [2022/04/01 19:58:55.156813, 3] ../../source3/smbd/negprot.c:637(reply_negprot) Requested protocol [LANMAN1.0] [2022/04/01 19:58:55.156840, 3] ../../source3/smbd/negprot.c:637(reply_negprot) Requested protocol [Windows for Workgroups 3.1a] [2022/04/01 19:58:55.156903, 3] ../../source3/smbd/negprot.c:637(reply_negprot) Requested protocol [LM1.2X002] [2022/04/01 19:58:55.156957, 3] ../../source3/smbd/negprot.c:637(reply_negprot) Requested protocol [LANMAN2.1] [2022/04/01 19:58:55.157013, 3] ../../source3/smbd/negprot.c:637(reply_negprot) Requested protocol [NT LM 0.12] [2022/04/01 19:58:55.157066, 3] ../../source3/smbd/negprot.c:637(reply_negprot) Requested protocol [SMB 2.002] [2022/04/01 19:58:55.157108, 3] ../../source3/smbd/negprot.c:637(reply_negprot) Requested protocol [SMB 2.???] [2022/04/01 19:58:55.157563, 3] ../../source3/smbd/smb2_negprot.c:301(smbd_smb2_request_process_negprot) Selected protocol SMB2_FF [2022/04/01 19:58:55.157965, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'gssapi_spnego' registered [2022/04/01 19:58:55.158011, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'gssapi_krb5' registered [2022/04/01 19:58:55.158039, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'gssapi_krb5_sasl' registered [2022/04/01 19:58:55.158085, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'spnego' registered [2022/04/01 19:58:55.158122, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'schannel' registered [2022/04/01 19:58:55.158162, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'naclrpc_as_system' registered [2022/04/01 19:58:55.158213, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'sasl-EXTERNAL' registered [2022/04/01 19:58:55.158268, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'ntlmssp' registered [2022/04/01 19:58:55.158324, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'ntlmssp_resume_ccache' registered [2022/04/01 19:58:55.158375, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'http_basic' registered [2022/04/01 19:58:55.158429, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'http_ntlm' registered [2022/04/01 19:58:55.158482, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'http_negotiate' registered [2022/04/01 19:58:55.160306, 3] ../../source3/smbd/negprot.c:777(reply_negprot) Selected protocol SMB 2.??? [2022/04/01 19:58:55.164652, 3] ../../source3/smbd/smb2_negprot.c:301(smbd_smb2_request_process_negprot) Selected protocol SMB3_11 [2022/04/01 19:58:55.232418, 3] ../../source3/auth/auth_generic.c:172(auth3_generate_session_info_pac) Kerberos ticket principal name is [ad.test@XXX.XXX-NET.DE] [2022/04/01 19:58:55.234127, 3] ../../source3/param/loadparm.c:3967(lp_load_ex) lp_load_ex: refreshing parameters [2022/04/01 19:58:55.234240, 3] ../../source3/param/loadparm.c:558(init_globals) Initialising global parameters [2022/04/01 19:58:55.234402, 3] ../../source3/param/loadparm.c:2869(lp_do_section) Processing section "[global]" [2022/04/01 19:58:55.235132, 3] ../../source3/param/loadparm.c:2869(lp_do_section) Processing section "[GLOBAL]" [2022/04/01 19:58:55.235294, 1] ../../lib/param/loadparm.c:1766(lpcfg_do_global_parameter) lpcfg_do_global_parameter: WARNING: The "syslog only" option is deprecated [2022/04/01 19:58:55.235663, 3] ../../source3/param/loadparm.c:1670(lp_add_ipc) adding IPC service [2022/04/01 19:58:55.235822, 3] ../../source3/smbd/password.c:84(register_homes_share) Adding homes service for user 'XXX\ad.test' using home directory: '/mnt/datapool/benutzer/XXX/ad.test' [2022/04/01 19:58:55.236226, 2] ../../source3/param/loadparm.c:2886(lp_do_section) Processing section "[homes]" [2022/04/01 19:58:55.236548, 3] ../../source3/param/loadparm.c:1620(lp_add_home) adding home's share [ad.test] for user 'XXX\ad.test' at '/mnt/datapool/benutzer/%D/%U' [2022/04/01 19:58:55.583545, 0] ../../source3/auth/pampass.c:89(smb_pam_error_handler) smb_pam_error_handler: PAM: session setup failed : Permission denied [2022/04/01 19:58:55.584266, 1] ../../source3/smbd/session.c:69(session_claim) pam_session rejected the session for XXX\ad.test [smb/3208429733] [2022/04/01 19:58:55.584311, 1] ../../source3/smbd/smb2_sesssetup.c:449(smbd_smb2_auth_generic_return) smb2: Failed to claim session for vuid=3208429733 [2022/04/01 19:58:55.584385, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_LOGON_FAILURE] || at ../../source3/smbd/smb2_sesssetup.c:147 [2022/04/01 19:58:55.600494, 3] ../../source3/smbd/server_exit.c:239(exit_server_common) Server exit (NT_STATUS_CONNECTION_RESET) [2022/04/01 19:58:56.310389, 2] ../../source3/lib/tallocmsg.c:84(register_msg_pool_usage) Registered MSG_REQ_POOL_USAGE [2022/04/01 19:58:56.310815, 3] ../../lib/util/access.c:316(allow_access) Allowed connection from 10.30.155.213 (10.30.155.213) [2022/04/01 19:58:56.311360, 3] ../../source3/smbd/oplock.c:1427(init_oplocks) init_oplocks: initializing messages. [2022/04/01 19:58:56.311483, 3] ../../source3/smbd/process.c:1957(process_smb) Transaction 0 of length 240 (0 toread) [2022/04/01 19:58:56.311836, 3] ../../source3/smbd/smb2_negprot.c:301(smbd_smb2_request_process_negprot) Selected protocol SMB3_11 [2022/04/01 19:58:56.312227, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'gssapi_spnego' registered [2022/04/01 19:58:56.312273, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'gssapi_krb5' registered [2022/04/01 19:58:56.312317, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'gssapi_krb5_sasl' registered [2022/04/01 19:58:56.312367, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'spnego' registered [2022/04/01 19:58:56.312413, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'schannel' registered [2022/04/01 19:58:56.312456, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'naclrpc_as_system' registered [2022/04/01 19:58:56.312504, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'sasl-EXTERNAL' registered [2022/04/01 19:58:56.312548, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'ntlmssp' registered [2022/04/01 19:58:56.312590, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'ntlmssp_resume_ccache' registered [2022/04/01 19:58:56.312633, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'http_basic' registered [2022/04/01 19:58:56.312680, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'http_ntlm' registered [2022/04/01 19:58:56.312723, 3] ../../auth/gensec/gensec_start.c:1082(gensec_register) GENSEC backend 'http_negotiate' registered [2022/04/01 19:58:56.339515, 3] ../../source3/auth/auth_generic.c:172(auth3_generate_session_info_pac) Kerberos ticket principal name is [ad.test@XXX.XXX-NET.DE] [2022/04/01 19:58:56.340632, 3] ../../source3/param/loadparm.c:3967(lp_load_ex) lp_load_ex: refreshing parameters [2022/04/01 19:58:56.340743, 3] ../../source3/param/loadparm.c:558(init_globals) Initialising global parameters [2022/04/01 19:58:56.340893, 3] ../../source3/param/loadparm.c:2869(lp_do_section) Processing section "[global]" [2022/04/01 19:58:56.341593, 3] ../../source3/param/loadparm.c:2869(lp_do_section) Processing section "[GLOBAL]" [2022/04/01 19:58:56.341754, 1] ../../lib/param/loadparm.c:1766(lpcfg_do_global_parameter) lpcfg_do_global_parameter: WARNING: The "syslog only" option is deprecated [2022/04/01 19:58:56.342115, 3] ../../source3/param/loadparm.c:1670(lp_add_ipc) adding IPC service [2022/04/01 19:58:56.342283, 3] ../../source3/smbd/password.c:84(register_homes_share) Adding homes service for user 'XXX\ad.test' using home directory: '/mnt/datapool/benutzer/XXX/ad.test' [2022/04/01 19:58:56.342717, 2] ../../source3/param/loadparm.c:2886(lp_do_section) Processing section "[homes]" [2022/04/01 19:58:56.343067, 3] ../../source3/param/loadparm.c:1620(lp_add_home) adding home's share [ad.test] for user 'XXX\ad.test' at '/mnt/datapool/benutzer/%D/%U' [2022/04/01 19:58:56.373728, 3] ../../lib/util/access.c:316(allow_access) Allowed connection from 10.30.155.213 (10.30.155.213) [2022/04/01 19:58:56.373864, 3] ../../source3/smbd/service.c:610(make_connection_snum) make_connection_snum: Connect path is '/tmp' for service [IPC$] [2022/04/01 19:58:56.373974, 3] ../../source3/smbd/vfs.c:115(vfs_init_default) Initialising default vfs hooks [2022/04/01 19:58:56.374043, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [/[Default VFS]/] [2022/04/01 19:58:56.374235, 3] ../../source3/smbd/service.c:865(make_connection_snum) 10.30.155.213 (ipv4:10.30.155.213:50731) connect to service IPC$ initially as user XXX\ad.test (uid=100001109, gid=100000514) (pid 295973) [2022/04/01 19:58:56.377009, 2] ../../source3/param/loadparm.c:2886(lp_do_section) Processing section "[firma]" [2022/04/01 19:58:56.377338, 3] ../../source3/smbd/msdfs.c:1081(get_referred_path) get_referred_path: |firma| in dfs path \truenas\firma is not a dfs root. [2022/04/01 19:58:56.377386, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_NOT_FOUND] || at ../../source3/smbd/smb2_ioctl.c:353 [2022/04/01 19:58:56.381086, 3] ../../lib/util/access.c:316(allow_access) Allowed connection from 10.30.155.213 (10.30.155.213) [2022/04/01 19:58:56.381185, 3] ../../source3/smbd/service.c:610(make_connection_snum) make_connection_snum: Connect path is '/mnt/datapool/firma' for service [firma] [2022/04/01 19:58:56.381285, 3] ../../source3/smbd/vfs.c:115(vfs_init_default) Initialising default vfs hooks [2022/04/01 19:58:56.381322, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [/[Default VFS]/] [2022/04/01 19:58:56.381371, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [io_uring] [2022/04/01 19:58:56.383583, 3] ../../lib/util/modules.c:167(load_module_absolute_path) load_module_absolute_path: Module '/usr/lib/x86_64-linux-gnu/samba/vfs/io_uring.so' loaded [2022/04/01 19:58:56.383644, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [zfs_core] [2022/04/01 19:58:56.401004, 3] ../../lib/util/modules.c:167(load_module_absolute_path) load_module_absolute_path: Module '/usr/lib/x86_64-linux-gnu/samba/vfs/zfs_core.so' loaded [2022/04/01 19:58:56.401070, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [nfs4acl_xattr] [2022/04/01 19:58:56.403100, 3] ../../lib/util/modules.c:167(load_module_absolute_path) load_module_absolute_path: Module '/usr/lib/x86_64-linux-gnu/samba/vfs/nfs4acl_xattr.so' loaded [2022/04/01 19:58:56.403157, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [shadow_copy_zfs] [2022/04/01 19:58:56.405147, 3] ../../lib/util/modules.c:167(load_module_absolute_path) load_module_absolute_path: Module '/usr/lib/x86_64-linux-gnu/samba/vfs/shadow_copy_zfs.so' loaded [2022/04/01 19:58:56.405199, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [streams_xattr] [2022/04/01 19:58:56.407028, 3] ../../lib/util/modules.c:167(load_module_absolute_path) load_module_absolute_path: Module '/usr/lib/x86_64-linux-gnu/samba/vfs/streams_xattr.so' loaded [2022/04/01 19:58:56.415344, 3] ../../source3/modules/vfs_nfs4acl_xattr.c:511(nfs4acl_connect) nfs4acl_connect: Setting 'inherit acls = true', 'dos filemode = true', 'force unknown acl user = true', 'create mask = 0666', 'directory mask = 0777' and 'store dos attributes = yes' for service [firma] [2022/04/01 19:58:56.415579, 2] ../../source3/smbd/service.c:865(make_connection_snum) 10.30.155.213 (ipv4:10.30.155.213:50731) connect to service firma initially as user XXX\ad.test (uid=100001109, gid=100000514) (pid 295973) [2022/04/01 19:58:56.455529, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) 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:337 [2022/04/01 19:58:56.464864, 2] ../../source3/param/loadparm.c:2886(lp_do_section) Processing section "[media]" [2022/04/01 19:58:56.465177, 3] ../../source3/smbd/msdfs.c:1081(get_referred_path) get_referred_path: |media| in dfs path \truenas\media is not a dfs root. [2022/04/01 19:58:56.465220, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_NOT_FOUND] || at ../../source3/smbd/smb2_ioctl.c:353 [2022/04/01 19:58:56.469521, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) 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:337 [2022/04/01 19:58:56.481206, 3] ../../lib/util/access.c:316(allow_access) Allowed connection from 10.30.155.213 (10.30.155.213) [2022/04/01 19:58:56.481349, 3] ../../source3/smbd/service.c:610(make_connection_snum) make_connection_snum: Connect path is '/mnt/datapool/media' for service [media] [2022/04/01 19:58:56.481428, 3] ../../source3/smbd/vfs.c:115(vfs_init_default) Initialising default vfs hooks [2022/04/01 19:58:56.481507, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [/[Default VFS]/] [2022/04/01 19:58:56.481561, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [io_uring] [2022/04/01 19:58:56.481612, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [zfs_core] [2022/04/01 19:58:56.481652, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [nfs4acl_xattr] [2022/04/01 19:58:56.481695, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [shadow_copy_zfs] [2022/04/01 19:58:56.481733, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [streams_xattr] [2022/04/01 19:58:56.482944, 3] ../../source3/modules/vfs_nfs4acl_xattr.c:511(nfs4acl_connect) nfs4acl_connect: Setting 'inherit acls = true', 'dos filemode = true', 'force unknown acl user = true', 'create mask = 0666', 'directory mask = 0777' and 'store dos attributes = yes' for service [media] [2022/04/01 19:58:56.483161, 2] ../../source3/smbd/service.c:865(make_connection_snum) 10.30.155.213 (ipv4:10.30.155.213:50731) connect to service media initially as user XXX\ad.test (uid=100001109, gid=100000514) (pid 295973) [2022/04/01 19:58:56.513849, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) 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:337 [2022/04/01 19:58:56.519967, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) 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:337 [2022/04/01 19:58:56.521836, 2] ../../source3/param/loadparm.c:2886(lp_do_section) Processing section "[alle]" [2022/04/01 19:58:56.522141, 3] ../../source3/smbd/msdfs.c:1081(get_referred_path) get_referred_path: |alle| in dfs path \truenas\alle is not a dfs root. [2022/04/01 19:58:56.522186, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_NOT_FOUND] || at ../../source3/smbd/smb2_ioctl.c:353 [2022/04/01 19:58:56.543269, 3] ../../lib/util/access.c:316(allow_access) Allowed connection from 10.30.155.213 (10.30.155.213) [2022/04/01 19:58:56.543415, 3] ../../source3/smbd/service.c:610(make_connection_snum) make_connection_snum: Connect path is '/mnt/datapool/alle' for service [alle] [2022/04/01 19:58:56.543499, 3] ../../source3/smbd/vfs.c:115(vfs_init_default) Initialising default vfs hooks [2022/04/01 19:58:56.543550, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [/[Default VFS]/] [2022/04/01 19:58:56.543626, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [io_uring] [2022/04/01 19:58:56.543686, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [zfs_core] [2022/04/01 19:58:56.543752, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [nfs4acl_xattr] [2022/04/01 19:58:56.543799, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [shadow_copy_zfs] [2022/04/01 19:58:56.543849, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [streams_xattr] [2022/04/01 19:58:56.545322, 3] ../../source3/modules/vfs_nfs4acl_xattr.c:511(nfs4acl_connect) nfs4acl_connect: Setting 'inherit acls = true', 'dos filemode = true', 'force unknown acl user = true', 'create mask = 0666', 'directory mask = 0777' and 'store dos attributes = yes' for service [alle] [2022/04/01 19:58:56.545542, 2] ../../source3/smbd/service.c:865(make_connection_snum) 10.30.155.213 (ipv4:10.30.155.213:50731) connect to service alle initially as user XXX\ad.test (uid=100001109, gid=100000514) (pid 295973) [2022/04/01 19:58:56.562242, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) 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:337 [2022/04/01 19:58:56.572094, 3] ../../lib/util/access.c:316(allow_access) Allowed connection from 10.30.155.213 (10.30.155.213) [2022/04/01 19:58:56.572248, 3] ../../source3/smbd/service.c:610(make_connection_snum) make_connection_snum: Connect path is '/mnt/datapool/benutzer/XXX/ad.test' for service [ad.test] [2022/04/01 19:58:56.572334, 3] ../../source3/smbd/vfs.c:115(vfs_init_default) Initialising default vfs hooks [2022/04/01 19:58:56.572384, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [/[Default VFS]/] [2022/04/01 19:58:56.572454, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [io_uring] [2022/04/01 19:58:56.572552, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [zfs_core] [2022/04/01 19:58:56.572652, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [nfs4acl_xattr] [2022/04/01 19:58:56.572750, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [shadow_copy_zfs] [2022/04/01 19:58:56.572837, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [streams_xattr] [2022/04/01 19:58:56.575312, 3] ../../source3/modules/vfs_nfs4acl_xattr.c:511(nfs4acl_connect) nfs4acl_connect: Setting 'inherit acls = true', 'dos filemode = true', 'force unknown acl user = true', 'create mask = 0666', 'directory mask = 0777' and 'store dos attributes = yes' for service [ad.test] [2022/04/01 19:58:56.575704, 2] ../../source3/smbd/service.c:865(make_connection_snum) 10.30.155.213 (ipv4:10.30.155.213:50731) connect to service ad.test initially as user XXX\ad.test (uid=100001109, gid=100000514) (pid 295973) [2022/04/01 19:58:56.578102, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) 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:337 [2022/04/01 19:58:56.608264, 0] ../../source3/smbd/service.c:168(chdir_current_service) chdir_current_service: vfs_ChDir(/mnt/datapool/benutzer/XXX/ad.test) failed: Permission denied. Current token: uid=100001109, gid=100000514, 7 groups: 100001109 100000514 100001113 90000029 90000030 90000032 90000002 [2022/04/01 19:58:56.608359, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] || at ../../source3/smbd/smb2_server.c:3245 [2022/04/01 19:58:56.617368, 0] ../../source3/smbd/service.c:168(chdir_current_service) chdir_current_service: vfs_ChDir(/mnt/datapool/benutzer/XXX/ad.test) failed: Permission denied. Current token: uid=100001109, gid=100000514, 7 groups: 100001109 100000514 100001113 90000029 90000030 90000032 90000002 [2022/04/01 19:58:56.617460, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] || at ../../source3/smbd/smb2_server.c:3245 [2022/04/01 19:58:56.637817, 0] ../../source3/smbd/service.c:168(chdir_current_service) chdir_current_service: vfs_ChDir(/mnt/datapool/benutzer/XXX/ad.test) failed: Permission denied. Current token: uid=100001109, gid=100000514, 7 groups: 100001109 100000514 100001113 90000029 90000030 90000032 90000002 [2022/04/01 19:58:56.637913, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] || at ../../source3/smbd/smb2_server.c:3245 [2022/04/01 19:58:57.198103, 0] ../../source3/smbd/service.c:168(chdir_current_service) chdir_current_service: vfs_ChDir(/mnt/datapool/benutzer/XXX/ad.test) failed: Permission denied. Current token: uid=100001109, gid=100000514, 7 groups: 100001109 100000514 100001113 90000029 90000030 90000032 90000002 [2022/04/01 19:58:57.198200, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] || at ../../source3/smbd/smb2_server.c:3245 [2022/04/01 19:58:57.204613, 0] ../../source3/smbd/service.c:168(chdir_current_service) chdir_current_service: vfs_ChDir(/mnt/datapool/benutzer/XXX/ad.test) failed: Permission denied. Current token: uid=100001109, gid=100000514, 7 groups: 100001109 100000514 100001113 90000029 90000030 90000032 90000002 [2022/04/01 19:58:57.204708, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] || at ../../source3/smbd/smb2_server.c:3245 [2022/04/01 19:58:57.210789, 0] ../../source3/smbd/service.c:168(chdir_current_service) chdir_current_service: vfs_ChDir(/mnt/datapool/benutzer/XXX/ad.test) failed: Permission denied. Current token: uid=100001109, gid=100000514, 7 groups: 100001109 100000514 100001113 90000029 90000030 90000032 90000002 [2022/04/01 19:58:57.210885, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] || at ../../source3/smbd/smb2_server.c:3245 [2022/04/01 19:58:57.223134, 0] ../../source3/smbd/service.c:168(chdir_current_service) chdir_current_service: vfs_ChDir(/mnt/datapool/benutzer/XXX/ad.test) failed: Permission denied. Current token: uid=100001109, gid=100000514, 7 groups: 100001109 100000514 100001113 90000029 90000030 90000032 90000002 [2022/04/01 19:58:57.223226, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] || at ../../source3/smbd/smb2_server.c:3245 [2022/04/01 19:59:05.078775, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) 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:337 [2022/04/01 19:59:05.079299, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) 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:337 [2022/04/01 19:59:05.079687, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) 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:337 [2022/04/01 19:59:05.079891, 0] ../../source3/smbd/service.c:168(chdir_current_service) chdir_current_service: vfs_ChDir(/mnt/datapool/benutzer/XXX/ad.test) failed: Permission denied. Current token: uid=100001109, gid=100000514, 7 groups: 100001109 100000514 100001113 90000029 90000030 90000032 90000002 [2022/04/01 19:59:05.080038, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] || at ../../source3/smbd/smb2_server.c:3245 [2022/04/01 19:59:05.082998, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) 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:337 [2022/04/01 19:59:05.083429, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) 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:337 [2022/04/01 19:59:05.083786, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) 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:337 [2022/04/01 19:59:05.083980, 0] ../../source3/smbd/service.c:168(chdir_current_service) chdir_current_service: vfs_ChDir(/mnt/datapool/benutzer/XXX/ad.test) failed: Permission denied. Current token: uid=100001109, gid=100000514, 7 groups: 100001109 100000514 100001113 90000029 90000030 90000032 90000002 [2022/04/01 19:59:05.084077, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] || at ../../source3/smbd/smb2_server.c:3245 [2022/04/01 19:59:05.084420, 0] ../../source3/smbd/service.c:168(chdir_current_service) chdir_current_service: vfs_ChDir(/mnt/datapool/benutzer/XXX/ad.test) failed: Permission denied. Current token: uid=100001109, gid=100000514, 7 groups: 100001109 100000514 100001113 90000029 90000030 90000032 90000002 [2022/04/01 19:59:05.084515, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] || at ../../source3/smbd/smb2_server.c:3245 [2022/04/01 19:59:05.088498, 0] ../../source3/smbd/service.c:168(chdir_current_service) chdir_current_service: vfs_ChDir(/mnt/datapool/benutzer/XXX/ad.test) failed: Permission denied. Current token: uid=100001109, gid=100000514, 7 groups: 100001109 100000514 100001113 90000029 90000030 90000032 90000002 [2022/04/01 19:59:05.088594, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] || at ../../source3/smbd/smb2_server.c:3245 [2022/04/01 19:59:05.636718, 0] ../../source3/smbd/service.c:168(chdir_current_service) chdir_current_service: vfs_ChDir(/mnt/datapool/benutzer/XXX/ad.test) failed: Permission denied. Current token: uid=100001109, gid=100000514, 7 groups: 100001109 100000514 100001113 90000029 90000030 90000032 90000002 [2022/04/01 19:59:05.636814, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] || at ../../source3/smbd/smb2_server.c:3245 [2022/04/01 19:59:05.641038, 0] ../../source3/smbd/service.c:168(chdir_current_service) chdir_current_service: vfs_ChDir(/mnt/datapool/benutzer/XXX/ad.test) failed: Permission denied. Current token: uid=100001109, gid=100000514, 7 groups: 100001109 100000514 100001113 90000029 90000030 90000032 90000002 [2022/04/01 19:59:05.641129, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] || at ../../source3/smbd/smb2_server.c:3245 [2022/04/01 19:59:05.659541, 0] ../../source3/smbd/service.c:168(chdir_current_service) chdir_current_service: vfs_ChDir(/mnt/datapool/benutzer/XXX/ad.test) failed: Permission denied. Current token: uid=100001109, gid=100000514, 7 groups: 100001109 100000514 100001113 90000029 90000030 90000032 90000002 [2022/04/01 19:59:05.659638, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] || at ../../source3/smbd/smb2_server.c:3245 [2022/04/01 19:59:05.664078, 0] ../../source3/smbd/service.c:168(chdir_current_service) chdir_current_service: vfs_ChDir(/mnt/datapool/benutzer/XXX/ad.test) failed: Permission denied. Current token: uid=100001109, gid=100000514, 7 groups: 100001109 100000514 100001113 90000029 90000030 90000032 90000002 [2022/04/01 19:59:05.664170, 3] ../../source3/smbd/smb2_server.c:3953(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] || at ../../source3/smbd/smb2_server.c:3245 [2022/04/01 19:59:07.225997, 3] ../../source3/smbd/service.c:1136(close_cnum) 10.30.155.213 (ipv4:10.30.155.213:50731) closed connection to service IPC$