[2016/06/20 21:28:43.862420, 10, pid=23196, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:369(read_smb_length_return_keepalive) got smb length of 104 [2016/06/20 21:28:43.862655, 6, pid=23196, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:1877(process_smb) got message type 0x0 of len 0x68 [2016/06/20 21:28:43.862720, 3, pid=23196, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:1879(process_smb) Transaction 32 of length 108 (0 toread) [2016/06/20 21:28:43.862965, 5, pid=23196, effective(0, 0), real(0, 0)] ../source3/lib/util.c:168(show_msg) [2016/06/20 21:28:43.863080, 5, pid=23196, effective(0, 0), real(0, 0)] ../source3/lib/util.c:178(show_msg) size=104 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=49153 smb_tid=15566 smb_pid=12667 smb_uid=59966 smb_mid=147 smt_wct=15 smb_vwv[ 0]= 18 (0x12) smb_vwv[ 1]= 18 (0x12) smb_vwv[ 2]= 2 (0x2) smb_vwv[ 3]= 1000 (0x3E8) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 18 (0x12) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 18 (0x12) smb_vwv[12]= 86 (0x56) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 6 (0x6) smb_bcc=39 [2016/06/20 21:28:43.866204, 10, pid=23196, effective(0, 0), real(0, 0)] ../lib/util/util.c:559(dump_data) [0000] 00 00 00 09 02 00 00 00 00 2F 00 7A 00 75 00 70 ........ ./.z.u.p [0010] 00 61 00 00 00 02 00 00 00 12 00 00 00 A4 81 00 .a...... ........ [0020] 00 00 00 00 00 00 02 ....... [2016/06/20 21:28:43.867014, 3, pid=23196, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:1489(switch_message) switch message SMBtrans2 (pid 23196) conn 0x559ceeae5d90 [2016/06/20 21:28:43.867182, 4, pid=23196, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (1000, 1000) - sec_ctx_stack_ndx = 0 [2016/06/20 21:28:43.867267, 5, pid=23196, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:63(security_token_debug) Security token SIDs (18): SID[ 0]: S-1-22-1-1000 SID[ 1]: S-1-22-2-1000 SID[ 2]: S-1-22-2-3 SID[ 3]: S-1-22-2-4 SID[ 4]: S-1-22-2-7 SID[ 5]: S-1-22-2-10 SID[ 6]: S-1-22-2-11 SID[ 7]: S-1-22-2-17 SID[ 8]: S-1-22-2-23 SID[ 9]: S-1-22-2-24 SID[ 10]: S-1-22-2-27 SID[ 11]: S-1-22-2-28 SID[ 12]: S-1-22-2-78 SID[ 13]: S-1-22-2-84 SID[ 14]: S-1-22-2-99 SID[ 15]: S-1-1-0 SID[ 16]: S-1-5-2 SID[ 17]: S-1-5-32-546 Privileges (0x 0): Rights (0x 0): [2016/06/20 21:28:43.868710, 5, pid=23196, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 1000 Primary group is 1000 and contains 14 supplementary groups Group[ 0]: 1000 Group[ 1]: 3 Group[ 2]: 4 Group[ 3]: 7 Group[ 4]: 10 Group[ 5]: 11 Group[ 6]: 17 Group[ 7]: 23 Group[ 8]: 24 Group[ 9]: 27 Group[ 10]: 28 Group[ 11]: 78 Group[ 12]: 84 Group[ 13]: 99 [2016/06/20 21:28:43.870120, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/uid.c:363(change_to_user_internal) Impersonated user: uid=(1000,1000), gid=(0,1000) [2016/06/20 21:28:43.870282, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/filename.c:257(unix_convert) unix_convert called on file "zupa" [2016/06/20 21:28:43.870350, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/filename.c:449(unix_convert) unix_convert begin: name = zupa, dirpath = , start = zupa [2016/06/20 21:28:43.870445, 3, pid=23196, effective(1000, 1000), real(1000, 0), class=vfs] ../source3/smbd/vfs.c:1174(check_reduced_name) check_reduced_name [zupa] [/mnt/storage-sdd-noraid/public] [2016/06/20 21:28:43.870535, 10, pid=23196, effective(1000, 1000), real(1000, 0), class=vfs] ../source3/smbd/vfs.c:1234(check_reduced_name) check_reduced_name realpath [zupa] -> [/mnt/storage-sdd-noraid/public/zupa] [2016/06/20 21:28:43.870597, 3, pid=23196, effective(1000, 1000), real(1000, 0), class=vfs] ../source3/smbd/vfs.c:1322(check_reduced_name) check_reduced_name: zupa reduced to /mnt/storage-sdd-noraid/public/zupa [2016/06/20 21:28:43.870658, 3, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/trans2.c:8503(call_trans2setfilepathinfo) call_trans2setfilepathinfo(6) zupa (fnum [fsp is NULL]) info_level=521 totdata=18 [2016/06/20 21:28:43.870720, 3, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/trans2.c:8080(smbd_do_setfilepathinfo) smbd_do_setfilepathinfo: zupa (fnum [fsp is NULL]) info_level=521 totdata=18 [2016/06/20 21:28:43.870783, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/trans2.c:7839(smb_posix_open) smb_posix_open: file zupa, smb_posix_flags = 0, mode 0644 [2016/06/20 21:28:43.870842, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/open.c:4982(create_file_default) create_file: access_mask = 0x112 file_attributes = 0x10001a4, share_access = 0x7, create_disposition = 0x3 create_options = 0x40 oplock_request = 0x1 private_flags = 0x0 root_dir_fid = 0x0, ea_list = 0x(nil), sd = 0x(nil), fname = zupa [2016/06/20 21:28:43.870912, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/open.c:4459(create_file_unixpath) create_file_unixpath: access_mask = 0x112 file_attributes = 0x10001a4, share_access = 0x7, create_disposition = 0x3 create_options = 0x40 oplock_request = 0x1 private_flags = 0x0 ea_list = 0x(nil), sd = 0x(nil), fname = zupa [2016/06/20 21:28:43.871016, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 1 for /var/lib/samba/smbXsrv_open_global.tdb [2016/06/20 21:28:43.871079, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) lock order: 1:/var/lib/samba/smbXsrv_open_global.tdb 2: 3: [2016/06/20 21:28:43.871151, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key) Locking key 83FF04D5 [2016/06/20 21:28:43.871241, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../lib/dbwrap/dbwrap_tdb.c:144(db_tdb_fetch_locked_internal) Allocated locked data 0x0x559ceeaeadf0 [2016/06/20 21:28:43.871409, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/smbXsrv_open.c:584(smbXsrv_open_global_verify_record) smbXsrv_open_global_verify_record: empty value [2016/06/20 21:28:43.871635, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/smbXsrv_open.c:702(smbXsrv_open_global_store) smbXsrv_open_global_store: key '83FF04D5' stored [2016/06/20 21:28:43.871718, 1, pid=23196, effective(1000, 1000), real(1000, 0)] ../librpc/ndr/ndr.c:402(ndr_print_debug) &global_blob: struct smbXsrv_open_globalB version : SMBXSRV_VERSION_0 (0) seqnum : 0x00000001 (1) info : union smbXsrv_open_globalU(case 0) info0 : * info0: struct smbXsrv_open_global0 db_rec : * server_id: struct server_id pid : 0x0000000000005a9c (23196) task_id : 0x00000000 (0) vnn : 0xffffffff (4294967295) unique_id : 0x9628c959b8733413 (-7626624581768104941) open_global_id : 0x83ff04d5 (2214528213) open_persistent_id : 0x0000000083ff04d5 (2214528213) open_volatile_id : 0x00000000000070ff (28927) open_owner : S-1-22-1-1000 open_time : pon cze 20 21:28:44 2016 CEST create_guid : 00000000-0000-0000-0000-000000000000 client_guid : 00000000-0000-0000-0000-000000000000 app_instance_id : 00000000-0000-0000-0000-000000000000 disconnect_time : NTTIME(0) durable_timeout_msec : 0x00000000 (0) durable : 0x00 (0) backend_cookie : DATA_BLOB length=0 [2016/06/20 21:28:43.872860, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key) Unlocking key 83FF04D5 [2016/06/20 21:28:43.872943, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 1 for /var/lib/samba/smbXsrv_open_global.tdb [2016/06/20 21:28:43.873012, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) lock order: 1: 2: 3: [2016/06/20 21:28:43.873089, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/smbXsrv_open.c:876(smbXsrv_open_create) smbXsrv_open_create: global_id (0x83ff04d5) stored [2016/06/20 21:28:43.873293, 1, pid=23196, effective(1000, 1000), real(1000, 0)] ../librpc/ndr/ndr.c:402(ndr_print_debug) &open_blob: struct smbXsrv_openB version : SMBXSRV_VERSION_0 (0) reserved : 0x00000000 (0) info : union smbXsrv_openU(case 0) info0 : * info0: struct smbXsrv_open table : * db_rec : NULL local_id : 0x000070ff (28927) global : * global: struct smbXsrv_open_global0 db_rec : NULL server_id: struct server_id pid : 0x0000000000005a9c (23196) task_id : 0x00000000 (0) vnn : 0xffffffff (4294967295) unique_id : 0x9628c959b8733413 (-7626624581768104941) open_global_id : 0x83ff04d5 (2214528213) open_persistent_id : 0x0000000083ff04d5 (2214528213) open_volatile_id : 0x00000000000070ff (28927) open_owner : S-1-22-1-1000 open_time : pon cze 20 21:28:44 2016 CEST create_guid : 00000000-0000-0000-0000-000000000000 client_guid : 00000000-0000-0000-0000-000000000000 app_instance_id : 00000000-0000-0000-0000-000000000000 disconnect_time : NTTIME(0) durable_timeout_msec : 0x00000000 (0) durable : 0x00 (0) backend_cookie : DATA_BLOB length=0 status : NT_STATUS_OK idle_time : pon cze 20 21:28:44 2016 CEST compat : NULL [2016/06/20 21:28:43.874939, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/files.c:129(file_new) allocated file structure fnum 28927 (1 used) [2016/06/20 21:28:43.874998, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/files.c:744(file_name_hash) file_name_hash: /mnt/storage-sdd-noraid/public/zupa hash 0xb107e9ed [2016/06/20 21:28:43.875039, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/open.c:2478(open_file_ntcreate) open_file_ntcreate: fname=zupa, dos_attrs=0x0 access_mask=0x112 share_access=0x7 create_disposition = 0x3 create_options=0x40 unix mode=0644 oplock_request=1 private_flags = 0x0 [2016/06/20 21:28:43.875085, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/open.c:2636(open_file_ntcreate) open_file_ntcreate: fname=zupa, after mapping access_mask=0x112 [2016/06/20 21:28:43.875127, 4, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/open.c:2726(open_file_ntcreate) calling open_file with flags=0x1 flags2=0x40 mode=0644, access_mask = 0x112, open_access_mask = 0x112 [2016/06/20 21:28:43.875180, 10, pid=23196, effective(1000, 1000), real(1000, 0), class=acls] ../source3/smbd/posix_acls.c:3558(posix_get_nt_acl) posix_get_nt_acl: called for file . [2016/06/20 21:28:43.875287, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/passdb/lookup_sid.c:1237(uid_to_sid) uid 1000 -> sid S-1-5-21-4285455791-229362118-3877793692-1000 [2016/06/20 21:28:43.875398, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/passdb/lookup_sid.c:1278(gid_to_sid) gid_to_sid: winbind failed to find a sid for gid 1000 [2016/06/20 21:28:43.875439, 4, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/sec_ctx.c:216(push_sec_ctx) push_sec_ctx(1000, 1000) : sec_ctx_stack_ndx = 1 [2016/06/20 21:28:43.875479, 4, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/uid.c:491(push_conn_ctx) push_conn_ctx(59966) : conn_ctx_stack_ndx = 0 [2016/06/20 21:28:43.875517, 4, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2016/06/20 21:28:43.875546, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2016/06/20 21:28:43.875573, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2016/06/20 21:28:43.875700, 4, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/sec_ctx.c:421(pop_sec_ctx) pop_sec_ctx (1000, 1000) - sec_ctx_stack_ndx = 0 [2016/06/20 21:28:43.875735, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/passdb/lookup_sid.c:1141(legacy_gid_to_sid) LEGACY: gid 1000 -> sid S-1-22-2-1000 [2016/06/20 21:28:43.875773, 10, pid=23196, effective(1000, 1000), real(1000, 0), class=acls] ../source3/smbd/posix_acls.c:2724(canonicalise_acl) canonicalise_acl: Access ace entries before arrange : [2016/06/20 21:28:43.875802, 10, pid=23196, effective(1000, 1000), real(1000, 0), class=acls] ../source3/smbd/posix_acls.c:2737(canonicalise_acl) canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms r-x [2016/06/20 21:28:43.875839, 10, pid=23196, effective(1000, 1000), real(1000, 0), class=acls] ../source3/smbd/posix_acls.c:2737(canonicalise_acl) canon_ace index 1. Type = allow SID = S-1-22-2-1000 gid 1000 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms r-x [2016/06/20 21:28:43.875954, 10, pid=23196, effective(1000, 1000), real(1000, 0), class=acls] ../source3/smbd/posix_acls.c:2737(canonicalise_acl) canon_ace index 2. Type = allow SID = S-1-5-21-4285455791-229362118-3877793692-1000 uid 1000 (arekm) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx [2016/06/20 21:28:43.876066, 10, pid=23196, effective(1000, 1000), real(1000, 0), class=acls] ../source3/smbd/posix_acls.c:848(print_canon_ace_list) print_canon_ace_list: canonicalise_acl: ace entries after arrange canon_ace index 0. Type = allow SID = S-1-5-21-4285455791-229362118-3877793692-1000 uid 1000 (arekm) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx canon_ace index 1. Type = allow SID = S-1-22-2-1000 gid 1000 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms r-x canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms r-x [2016/06/20 21:28:43.876212, 10, pid=23196, effective(1000, 1000), real(1000, 0), class=acls] ../source3/smbd/posix_acls.c:1110(map_canon_ace_perms) map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff [2016/06/20 21:28:43.876244, 10, pid=23196, effective(1000, 1000), real(1000, 0), class=acls] ../source3/smbd/posix_acls.c:1110(map_canon_ace_perms) map_canon_ace_perms: Mapped (UNIX) 140 to (NT) 1200a9 [2016/06/20 21:28:43.876272, 10, pid=23196, effective(1000, 1000), real(1000, 0), class=acls] ../source3/smbd/posix_acls.c:1110(map_canon_ace_perms) map_canon_ace_perms: Mapped (UNIX) 140 to (NT) 1200a9 [2016/06/20 21:28:43.876319, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/open.c:300(check_parent_access) check_parent_access: access check on directory . for path zupa for mask 0x2 returned (0x2) NT_STATUS_ACCESS_DENIED [2016/06/20 21:28:43.876353, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/open.c:866(open_file) open_file: check_parent_access on file zupa returned NT_STATUS_ACCESS_DENIED [2016/06/20 21:28:43.876386, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 1 for /var/lib/samba/smbXsrv_open_global.tdb [2016/06/20 21:28:43.876426, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) lock order: 1:/var/lib/samba/smbXsrv_open_global.tdb 2: 3: [2016/06/20 21:28:43.876462, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key) Locking key 83FF04D5 [2016/06/20 21:28:43.876502, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../lib/dbwrap/dbwrap_tdb.c:144(db_tdb_fetch_locked_internal) Allocated locked data 0x0x559ceeaea140 [2016/06/20 21:28:43.876550, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key) Unlocking key 83FF04D5 [2016/06/20 21:28:43.876583, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 1 for /var/lib/samba/smbXsrv_open_global.tdb [2016/06/20 21:28:43.876611, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) lock order: 1: 2: 3: [2016/06/20 21:28:43.876651, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/files.c:555(file_free) freed files structure 28927 (0 used) [2016/06/20 21:28:43.876679, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/open.c:4803(create_file_unixpath) create_file_unixpath: NT_STATUS_ACCESS_DENIED [2016/06/20 21:28:43.876706, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/open.c:5079(create_file_default) create_file: NT_STATUS_ACCESS_DENIED [2016/06/20 21:28:43.876740, 3, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/error.c:82(error_packet_set) NT error packet at ../source3/smbd/error.c(165) cmd=50 (SMBtrans2) NT_STATUS_ACCESS_DENIED [2016/06/20 21:28:43.876844, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/lib/util.c:168(show_msg) [2016/06/20 21:28:43.876870, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/lib/util.c:178(show_msg) size=35 smb_com=0x32 smb_rcls=34 smb_reh=0 smb_err=49152 smb_flg=128 smb_flg2=51203 smb_tid=15566 smb_pid=12667 smb_uid=59966 smb_mid=147 smt_wct=0 smb_bcc=0 [2016/06/20 21:28:43.876965, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../lib/util/util.c:559(dump_data) [2016/06/20 21:28:43.879142, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/lib/util_sock.c:369(read_smb_length_return_keepalive) got smb length of 84 [2016/06/20 21:28:43.879309, 6, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/process.c:1877(process_smb) got message type 0x0 of len 0x54 [2016/06/20 21:28:43.879343, 3, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/process.c:1879(process_smb) Transaction 33 of length 88 (0 toread) [2016/06/20 21:28:43.879373, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/lib/util.c:168(show_msg) [2016/06/20 21:28:43.879396, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/lib/util.c:178(show_msg) size=84 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=49153 smb_tid=15566 smb_pid=12667 smb_uid=59966 smb_mid=148 smt_wct=15 smb_vwv[ 0]= 18 (0x12) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 2 (0x2) smb_vwv[ 3]= 4000 (0xFA0) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 18 (0x12) smb_vwv[10]= 66 (0x42) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=19 [2016/06/20 21:28:43.880344, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../lib/util/util.c:559(dump_data) [0000] 00 00 02 00 00 00 00 2F 00 7A 00 75 00 70 00 61 ......./ .z.u.p.a [0010] 00 00 00 ... [2016/06/20 21:28:43.880570, 3, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/process.c:1489(switch_message) switch message SMBtrans2 (pid 23196) conn 0x559ceeae5d90 [2016/06/20 21:28:43.880693, 4, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (1000, 1000) - sec_ctx_stack_ndx = 0 [2016/06/20 21:28:43.880741, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../libcli/security/security_token.c:63(security_token_debug) Security token SIDs (18): SID[ 0]: S-1-22-1-1000 SID[ 1]: S-1-22-2-1000 SID[ 2]: S-1-22-2-3 SID[ 3]: S-1-22-2-4 SID[ 4]: S-1-22-2-7 SID[ 5]: S-1-22-2-10 SID[ 6]: S-1-22-2-11 SID[ 7]: S-1-22-2-17 SID[ 8]: S-1-22-2-23 SID[ 9]: S-1-22-2-24 SID[ 10]: S-1-22-2-27 SID[ 11]: S-1-22-2-28 SID[ 12]: S-1-22-2-78 SID[ 13]: S-1-22-2-84 SID[ 14]: S-1-22-2-99 SID[ 15]: S-1-1-0 SID[ 16]: S-1-5-2 SID[ 17]: S-1-5-32-546 Privileges (0x 0): Rights (0x 0): [2016/06/20 21:28:43.881512, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 1000 Primary group is 1000 and contains 14 supplementary groups Group[ 0]: 1000 Group[ 1]: 3 Group[ 2]: 4 Group[ 3]: 7 Group[ 4]: 10 Group[ 5]: 11 Group[ 6]: 17 Group[ 7]: 23 Group[ 8]: 24 Group[ 9]: 27 Group[ 10]: 28 Group[ 11]: 78 Group[ 12]: 84 Group[ 13]: 99 [2016/06/20 21:28:43.882029, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/uid.c:363(change_to_user_internal) Impersonated user: uid=(1000,1000), gid=(0,1000) [2016/06/20 21:28:43.882108, 3, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/trans2.c:5564(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512 [2016/06/20 21:28:43.882154, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/filename.c:257(unix_convert) unix_convert called on file "zupa" [2016/06/20 21:28:43.882186, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/filename.c:449(unix_convert) unix_convert begin: name = zupa, dirpath = , start = zupa [2016/06/20 21:28:43.882269, 3, pid=23196, effective(1000, 1000), real(1000, 0), class=vfs] ../source3/smbd/vfs.c:1174(check_reduced_name) check_reduced_name [zupa] [/mnt/storage-sdd-noraid/public] [2016/06/20 21:28:43.882321, 10, pid=23196, effective(1000, 1000), real(1000, 0), class=vfs] ../source3/smbd/vfs.c:1234(check_reduced_name) check_reduced_name realpath [zupa] -> [/mnt/storage-sdd-noraid/public/zupa] [2016/06/20 21:28:43.882353, 3, pid=23196, effective(1000, 1000), real(1000, 0), class=vfs] ../source3/smbd/vfs.c:1322(check_reduced_name) check_reduced_name: zupa reduced to /mnt/storage-sdd-noraid/public/zupa [2016/06/20 21:28:43.882384, 3, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/trans2.c:5670(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_VFS_LSTAT of zupa failed (Nie ma takiego pliku ani katalogu) [2016/06/20 21:28:43.882428, 3, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/smbd/error.c:82(error_packet_set) NT error packet at ../source3/smbd/trans2.c(5672) cmd=50 (SMBtrans2) NT_STATUS_OBJECT_NAME_NOT_FOUND [2016/06/20 21:28:43.882463, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/lib/util.c:168(show_msg) [2016/06/20 21:28:43.882484, 5, pid=23196, effective(1000, 1000), real(1000, 0)] ../source3/lib/util.c:178(show_msg) size=35 smb_com=0x32 smb_rcls=52 smb_reh=0 smb_err=49152 smb_flg=128 smb_flg2=51203 smb_tid=15566 smb_pid=12667 smb_uid=59966 smb_mid=148 smt_wct=0 smb_bcc=0 [2016/06/20 21:28:43.882562, 10, pid=23196, effective(1000, 1000), real(1000, 0)] ../lib/util/util.c:559(dump_data)