Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.012998, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_server.c:4995(smbd_smb2_io_handler) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_request idx[1] of 5 vectors Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013059, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2_credits] ../../source3/smbd/smb2_server.c:712(smb2_validate_sequence_number) Nov 21 22:26:45 Hippo smbd[5707]: smb2_validate_sequence_number: smb2_validate_sequence_number: clearing id 106 (position 106) from bitmap Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013082, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_server.c:2980(smbd_smb2_request_dispatch) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_request_dispatch: opcode[SMB2_OP_CREATE] mid = 106 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013124, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/uid.c:327(change_to_user_impersonate) Nov 21 22:26:45 Hippo smbd[5707]: change_to_user_impersonate: Skipping user change - already user Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013171, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/uid.c:294(print_impersonation_info) Nov 21 22:26:45 Hippo smbd[5707]: print_impersonation_info: Impersonated user: uid=(1000,1000), gid=(0,100), cwd=[/mnt/user/incoming] Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013212, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_create.c:743(smbd_smb2_create_send) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_create_send: name [temp2\temp.txt] Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013250, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_create.c:852(smbd_smb2_create_send) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_create_send: open execution phase Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013285, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/files.c:766(openat_pathref_dirfsp_nosymlink) Nov 21 22:26:45 Hippo smbd[5707]: openat_pathref_dirfsp_nosymlink: path_in=temp2 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013316, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/files.c:76(fsp_new) Nov 21 22:26:45 Hippo smbd[5707]: fsp_new: allocated files structure (2 used) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013490, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/files.c:2005(file_name_hash) Nov 21 22:26:45 Hippo smbd[5707]: file_name_hash: /mnt/user/incoming/temp2 hash 0x4715f3bd Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013529, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/files.c:1104(openat_pathref_dirfsp_nosymlink) Nov 21 22:26:45 Hippo smbd[5707]: openat_pathref_dirfsp_nosymlink: returning temp2 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013552, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/files.c:559(openat_pathref_fsp) Nov 21 22:26:45 Hippo smbd[5707]: openat_pathref_fsp: smb_fname [temp.txt] Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013572, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/files.c:459(openat_pathref_fullname) Nov 21 22:26:45 Hippo smbd[5707]: openat_pathref_fullname: smb_fname [temp.txt] Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013589, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/files.c:76(fsp_new) Nov 21 22:26:45 Hippo smbd[5707]: fsp_new: allocated files structure (3 used) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013611, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/files.c:2005(file_name_hash) Nov 21 22:26:45 Hippo smbd[5707]: file_name_hash: /mnt/user/incoming/temp2/temp.txt hash 0xf9801c3b Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013822, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:975(fd_openat) Nov 21 22:26:45 Hippo smbd[5707]: fd_openat: name temp.txt, flags = 04000 mode = 00, fd = 32 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013861, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/files.c:528(openat_pathref_fullname) Nov 21 22:26:45 Hippo smbd[5707]: openat_pathref_fullname: fsp [temp2/temp.txt]: OK Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013886, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:6251(create_file_default) Nov 21 22:26:45 Hippo smbd[5707]: create_file_default: create_file: access_mask = 0x10000 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x40 oplock_request = 0x0 private_flags = 0x0 ea_list = (nil), sd = (nil), fname = temp2/temp.txt Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013908, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:5668(create_file_unixpath) Nov 21 22:26:45 Hippo smbd[5707]: create_file_unixpath: access_mask = 0x10000 file_attributes = 0x80 share_access = 0x7 create_disposition = 0x1 create_options = 0x40 oplock_request = 0x0 private_flags = 0x0 ea_list = (nil), sd = (nil), fname = temp2/temp.txt Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013950, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap.c:143(dbwrap_lock_order_lock) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_lock_order_lock: check lock order 1 for /var/cache/samba/smbXsrv_open_global.tdb Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.013981, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap.c:128(debug_lock_order) Nov 21 22:26:45 Hippo smbd[5707]: lock order: 1:/var/cache/samba/smbXsrv_open_global.tdb 2: 3: 4: Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.014015, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key) Nov 21 22:26:45 Hippo smbd[5707]: db_tdb_log_key: Locking key DCDA5258 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.014045, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap_tdb.c:153(db_tdb_fetch_locked_internal) Nov 21 22:26:45 Hippo smbd[5707]: db_tdb_fetch_locked_internal: Allocated locked data 0x55a398976670 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.014076, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/smbXsrv_open.c:633(smbXsrv_open_global_verify_record) Nov 21 22:26:45 Hippo smbd[5707]: smbXsrv_open_global_verify_record: empty value Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.014142, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/smbXsrv_open.c:751(smbXsrv_open_global_store) Nov 21 22:26:45 Hippo smbd[5707]: smbXsrv_open_global_store: key 'DCDA5258' stored Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.014169, 1, pid=5707, effective(1000, 100), real(1000, 0), class=rpc_parse] ../../source3/smbd/smbXsrv_open.c:753(smbXsrv_open_global_store) Nov 21 22:26:45 Hippo smbd[5707]: &global_blob: struct smbXsrv_open_globalB Nov 21 22:26:45 Hippo smbd[5707]: version : SMBXSRV_VERSION_0 (0) Nov 21 22:26:45 Hippo smbd[5707]: seqnum : 0x00000001 (1) Nov 21 22:26:45 Hippo smbd[5707]: info : union smbXsrv_open_globalU(case 0) Nov 21 22:26:45 Hippo smbd[5707]: info0 : * Nov 21 22:26:45 Hippo smbd[5707]: info0: struct smbXsrv_open_global0 Nov 21 22:26:45 Hippo smbd[5707]: db_rec : * Nov 21 22:26:45 Hippo smbd[5707]: server_id: struct server_id Nov 21 22:26:45 Hippo smbd[5707]: pid : 0x000000000000164b (5707) Nov 21 22:26:45 Hippo smbd[5707]: task_id : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: vnn : 0xffffffff (4294967295) Nov 21 22:26:45 Hippo smbd[5707]: unique_id : 0xb0900bc0689cc508 (-5724062205371169528) Nov 21 22:26:45 Hippo smbd[5707]: open_global_id : 0xdcda5258 (3705295448) Nov 21 22:26:45 Hippo smbd[5707]: open_persistent_id : 0x00000000dcda5258 (3705295448) Nov 21 22:26:45 Hippo smbd[5707]: open_volatile_id : 0x00000000bb368c80 (3140914304) Nov 21 22:26:45 Hippo smbd[5707]: open_owner : S-1-5-21-798852891-1520055758-2825886587-3000 Nov 21 22:26:45 Hippo smbd[5707]: open_time : Mon Nov 21 22:26:45 2022 CST Nov 21 22:26:45 Hippo smbd[5707]: create_guid : 00000000-0000-0000-0000-000000000000 Nov 21 22:26:45 Hippo smbd[5707]: client_guid : 713c5038-9e7a-4787-b196-9a1f56afabc6 Nov 21 22:26:45 Hippo smbd[5707]: app_instance_id : 00000000-0000-0000-0000-000000000000 Nov 21 22:26:45 Hippo smbd[5707]: disconnect_time : NTTIME(0) Nov 21 22:26:45 Hippo smbd[5707]: durable_timeout_msec : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: durable : 0x00 (0) Nov 21 22:26:45 Hippo smbd[5707]: backend_cookie : DATA_BLOB length=0 Nov 21 22:26:45 Hippo smbd[5707]: channel_sequence : 0x0000 (0) Nov 21 22:26:45 Hippo smbd[5707]: channel_generation : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: lock_sequence_array: ARRAY(64) Nov 21 22:26:45 Hippo smbd[5707]: [0000] FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF ........ ........ Nov 21 22:26:45 Hippo smbd[5707]: [0010] FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF ........ ........ Nov 21 22:26:45 Hippo smbd[5707]: [0020] FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF ........ ........ Nov 21 22:26:45 Hippo smbd[5707]: [0030] FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF ........ ........ Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.014655, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key) Nov 21 22:26:45 Hippo smbd[5707]: db_tdb_log_key: Unlocking key DCDA5258 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.014686, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap.c:175(dbwrap_lock_order_unlock) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_lock_order_unlock: release lock order 1 for /var/cache/samba/smbXsrv_open_global.tdb Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.014720, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/smbXsrv_open.c:917(smbXsrv_open_create) Nov 21 22:26:45 Hippo smbd[5707]: smbXsrv_open_create: global_id (0xdcda5258) stored Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.014748, 1, pid=5707, effective(1000, 100), real(1000, 0), class=rpc_parse] ../../source3/smbd/smbXsrv_open.c:919(smbXsrv_open_create) Nov 21 22:26:45 Hippo smbd[5707]: &open_blob: struct smbXsrv_openB Nov 21 22:26:45 Hippo smbd[5707]: version : SMBXSRV_VERSION_0 (0) Nov 21 22:26:45 Hippo smbd[5707]: reserved : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: info : union smbXsrv_openU(case 0) Nov 21 22:26:45 Hippo smbd[5707]: info0 : * Nov 21 22:26:45 Hippo smbd[5707]: info0: struct smbXsrv_open Nov 21 22:26:45 Hippo smbd[5707]: table : * Nov 21 22:26:45 Hippo smbd[5707]: db_rec : NULL Nov 21 22:26:45 Hippo smbd[5707]: local_id : 0xbb368c80 (3140914304) Nov 21 22:26:45 Hippo smbd[5707]: global : * Nov 21 22:26:45 Hippo smbd[5707]: global: struct smbXsrv_open_global0 Nov 21 22:26:45 Hippo smbd[5707]: db_rec : NULL Nov 21 22:26:45 Hippo smbd[5707]: server_id: struct server_id Nov 21 22:26:45 Hippo smbd[5707]: pid : 0x000000000000164b (5707) Nov 21 22:26:45 Hippo smbd[5707]: task_id : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: vnn : 0xffffffff (4294967295) Nov 21 22:26:45 Hippo smbd[5707]: unique_id : 0xb0900bc0689cc508 (-5724062205371169528) Nov 21 22:26:45 Hippo smbd[5707]: open_global_id : 0xdcda5258 (3705295448) Nov 21 22:26:45 Hippo smbd[5707]: open_persistent_id : 0x00000000dcda5258 (3705295448) Nov 21 22:26:45 Hippo smbd[5707]: open_volatile_id : 0x00000000bb368c80 (3140914304) Nov 21 22:26:45 Hippo smbd[5707]: open_owner : S-1-5-21-798852891-1520055758-2825886587-3000 Nov 21 22:26:45 Hippo smbd[5707]: open_time : Mon Nov 21 22:26:45 2022 CST Nov 21 22:26:45 Hippo smbd[5707]: create_guid : 00000000-0000-0000-0000-000000000000 Nov 21 22:26:45 Hippo smbd[5707]: client_guid : 713c5038-9e7a-4787-b196-9a1f56afabc6 Nov 21 22:26:45 Hippo smbd[5707]: app_instance_id : 00000000-0000-0000-0000-000000000000 Nov 21 22:26:45 Hippo smbd[5707]: disconnect_time : NTTIME(0) Nov 21 22:26:45 Hippo smbd[5707]: durable_timeout_msec : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: durable : 0x00 (0) Nov 21 22:26:45 Hippo smbd[5707]: backend_cookie : DATA_BLOB length=0 Nov 21 22:26:45 Hippo smbd[5707]: channel_sequence : 0x0000 (0) Nov 21 22:26:45 Hippo smbd[5707]: channel_generation : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: lock_sequence_array: ARRAY(64) Nov 21 22:26:45 Hippo smbd[5707]: [0000] FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF ........ ........ Nov 21 22:26:45 Hippo smbd[5707]: [0010] FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF ........ ........ Nov 21 22:26:45 Hippo smbd[5707]: [0020] FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF ........ ........ Nov 21 22:26:45 Hippo smbd[5707]: [0030] FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF ........ ........ Nov 21 22:26:45 Hippo smbd[5707]: status : NT_STATUS_OK Nov 21 22:26:45 Hippo smbd[5707]: idle_time : Mon Nov 21 22:26:45 2022 CST Nov 21 22:26:45 Hippo smbd[5707]: compat : NULL Nov 21 22:26:45 Hippo smbd[5707]: flags : 0x00 (0) Nov 21 22:26:45 Hippo smbd[5707]: 0: SMBXSRV_OPEN_NEED_REPLAY_CACHE Nov 21 22:26:45 Hippo smbd[5707]: 0: SMBXSRV_OPEN_HAVE_REPLAY_CACHE Nov 21 22:26:45 Hippo smbd[5707]: create_action : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: request_count : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: pre_request_count : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.015430, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/files.c:134(fsp_bind_smb) Nov 21 22:26:45 Hippo smbd[5707]: fsp_bind_smb: fsp [temp2/temp.txt] mid [106] Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.015466, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:181(unix_mode) Nov 21 22:26:45 Hippo smbd[5707]: unix_mode: unix_mode(temp2/temp.txt) returning 0666 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.015494, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:3603(open_file_ntcreate) Nov 21 22:26:45 Hippo smbd[5707]: open_file_ntcreate: fname=temp2/temp.txt, dos_attrs=0x80 access_mask=0x10000 share_access=0x7 create_disposition = 0x1 create_options=0x40 unix mode=0666 oplock_request=0 private_flags = 0x0 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.015697, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:260(parse_dos_attribute_blob) Nov 21 22:26:45 Hippo smbd[5707]: parse_dos_attribute_blob: temp2/temp.txt attr = Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.015727, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:328(parse_dos_attribute_blob) Nov 21 22:26:45 Hippo smbd[5707]: parse_dos_attribute_blob: file [temp2/temp.txt] creation time [Mon Nov 21 22:25:37 2022 CST] Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.015761, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:68(dos_mode_debug_print) Nov 21 22:26:45 Hippo smbd[5707]: dos_mode_debug_print: parse_dos_attribute_blob returning (0x20): "a" Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.015794, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:3786(open_file_ntcreate) Nov 21 22:26:45 Hippo smbd[5707]: open_file_ntcreate: fname=temp2/temp.txt, after mapping access_mask=0x10000 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.015824, 4, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:3886(open_file_ntcreate) Nov 21 22:26:45 Hippo smbd[5707]: calling open_file with flags=0x0 flags2=0x800 mode=0666, access_mask = 0x10000, open_access_mask = 0x10000 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.015855, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:3350(posix_fget_nt_acl) Nov 21 22:26:45 Hippo smbd[5707]: posix_fget_nt_acl: called for file temp2/temp.txt Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.016034, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/passdb/lookup_sid.c:1201(xid_to_sid) Nov 21 22:26:45 Hippo smbd[5707]: xid_to_sid: UID 1000 -> S-1-5-21-798852891-1520055758-2825886587-3000 from cache Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.016063, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/passdb/lookup_sid.c:1201(xid_to_sid) Nov 21 22:26:45 Hippo smbd[5707]: xid_to_sid: GID 100 -> S-0-0 from cache Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.016094, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/passdb/lookup_sid.c:1256(xid_to_sid) Nov 21 22:26:45 Hippo smbd[5707]: xid_to_sid: GID 100 -> S-1-22-2-100 fallback Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.016125, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:2692(canonicalise_acl) Nov 21 22:26:45 Hippo smbd[5707]: canonicalise_acl: Access ace entries before arrange : Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.016153, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:2705(canonicalise_acl) Nov 21 22:26:45 Hippo smbd[5707]: canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms rw- Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.016183, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:2705(canonicalise_acl) Nov 21 22:26:45 Hippo smbd[5707]: canon_ace index 1. Type = allow SID = S-1-22-2-100 gid 100 SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rw- Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.016212, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:2705(canonicalise_acl) Nov 21 22:26:45 Hippo smbd[5707]: canon_ace index 2. Type = allow SID = S-1-5-21-798852891-1520055758-2825886587-3000 uid 1000 SMB_ACL_USER_OBJ ace_flags = 0x0 perms rw- Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.016243, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:766(print_canon_ace_list) Nov 21 22:26:45 Hippo smbd[5707]: print_canon_ace_list: canonicalise_acl: ace entries after arrange Nov 21 22:26:45 Hippo smbd[5707]: canon_ace index 0. Type = allow SID = S-1-5-21-798852891-1520055758-2825886587-3000 uid 1000 SMB_ACL_USER_OBJ ace_flags = 0x0 perms rw- Nov 21 22:26:45 Hippo smbd[5707]: canon_ace index 1. Type = allow SID = S-1-22-2-100 gid 100 SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rw- Nov 21 22:26:45 Hippo smbd[5707]: canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms rw- Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.016315, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:1029(map_canon_ace_perms) Nov 21 22:26:45 Hippo smbd[5707]: map_canon_ace_perms: Mapped (UNIX) 180 to (NT) 12019f Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.016344, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:1029(map_canon_ace_perms) Nov 21 22:26:45 Hippo smbd[5707]: map_canon_ace_perms: Mapped (UNIX) 180 to (NT) 12019f Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.016373, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:1029(map_canon_ace_perms) Nov 21 22:26:45 Hippo smbd[5707]: map_canon_ace_perms: Mapped (UNIX) 180 to (NT) 12019f Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.016410, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:180(smbd_check_access_rights_sd) Nov 21 22:26:45 Hippo smbd[5707]: smbd_check_access_rights_sd: File [temp2/temp.txt] requesting [0x10000] returning [0x10000] (NT_STATUS_ACCESS_DENIED) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.016446, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:189(smbd_check_access_rights_sd) Nov 21 22:26:45 Hippo smbd[5707]: smbd_check_access_rights_sd: acl for temp2/temp.txt is: Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.016474, 1, pid=5707, effective(1000, 100), real(1000, 0), class=rpc_parse] ../../source3/smbd/open.c:191(smbd_check_access_rights_sd) Nov 21 22:26:45 Hippo smbd[5707]: sd: struct security_descriptor Nov 21 22:26:45 Hippo smbd[5707]: revision : SECURITY_DESCRIPTOR_REVISION_1 (1) Nov 21 22:26:45 Hippo smbd[5707]: type : 0x9004 (36868) Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_DESC_OWNER_DEFAULTED Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_DESC_GROUP_DEFAULTED Nov 21 22:26:45 Hippo smbd[5707]: 1: SEC_DESC_DACL_PRESENT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_DESC_DACL_DEFAULTED Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_DESC_SACL_PRESENT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_DESC_SACL_DEFAULTED Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_DESC_DACL_TRUSTED Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_DESC_SERVER_SECURITY Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_DESC_DACL_AUTO_INHERIT_REQ Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_DESC_SACL_AUTO_INHERIT_REQ Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_DESC_DACL_AUTO_INHERITED Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_DESC_SACL_AUTO_INHERITED Nov 21 22:26:45 Hippo smbd[5707]: 1: SEC_DESC_DACL_PROTECTED Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_DESC_SACL_PROTECTED Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_DESC_RM_CONTROL_VALID Nov 21 22:26:45 Hippo smbd[5707]: 1: SEC_DESC_SELF_RELATIVE Nov 21 22:26:45 Hippo smbd[5707]: owner_sid : * Nov 21 22:26:45 Hippo smbd[5707]: owner_sid : S-1-5-21-798852891-1520055758-2825886587-3000 Nov 21 22:26:45 Hippo smbd[5707]: group_sid : * Nov 21 22:26:45 Hippo smbd[5707]: group_sid : S-1-22-2-100 Nov 21 22:26:45 Hippo smbd[5707]: sacl : NULL Nov 21 22:26:45 Hippo smbd[5707]: dacl : * Nov 21 22:26:45 Hippo smbd[5707]: dacl: struct security_acl Nov 21 22:26:45 Hippo smbd[5707]: revision : SECURITY_ACL_REVISION_NT4 (2) Nov 21 22:26:45 Hippo smbd[5707]: size : 0x0058 (88) Nov 21 22:26:45 Hippo smbd[5707]: num_aces : 0x00000003 (3) Nov 21 22:26:45 Hippo smbd[5707]: aces: ARRAY(3) Nov 21 22:26:45 Hippo smbd[5707]: aces: struct security_ace Nov 21 22:26:45 Hippo smbd[5707]: type : SEC_ACE_TYPE_ACCESS_ALLOWED (0) Nov 21 22:26:45 Hippo smbd[5707]: flags : 0x00 (0) Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_OBJECT_INHERIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_CONTAINER_INHERIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_NO_PROPAGATE_INHERIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_INHERIT_ONLY Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_INHERITED_ACE Nov 21 22:26:45 Hippo smbd[5707]: 0x00: SEC_ACE_FLAG_VALID_INHERIT (0) Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_SUCCESSFUL_ACCESS Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_FAILED_ACCESS Nov 21 22:26:45 Hippo smbd[5707]: size : 0x0024 (36) Nov 21 22:26:45 Hippo smbd[5707]: access_mask : 0x0012019f (1180063) Nov 21 22:26:45 Hippo smbd[5707]: object : union security_ace_object_ctr(case 0) Nov 21 22:26:45 Hippo smbd[5707]: trustee : S-1-5-21-798852891-1520055758-2825886587-3000 Nov 21 22:26:45 Hippo smbd[5707]: aces: struct security_ace Nov 21 22:26:45 Hippo smbd[5707]: type : SEC_ACE_TYPE_ACCESS_ALLOWED (0) Nov 21 22:26:45 Hippo smbd[5707]: flags : 0x00 (0) Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_OBJECT_INHERIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_CONTAINER_INHERIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_NO_PROPAGATE_INHERIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_INHERIT_ONLY Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_INHERITED_ACE Nov 21 22:26:45 Hippo smbd[5707]: 0x00: SEC_ACE_FLAG_VALID_INHERIT (0) Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_SUCCESSFUL_ACCESS Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_FAILED_ACCESS Nov 21 22:26:45 Hippo smbd[5707]: size : 0x0018 (24) Nov 21 22:26:45 Hippo smbd[5707]: access_mask : 0x0012019f (1180063) Nov 21 22:26:45 Hippo smbd[5707]: object : union security_ace_object_ctr(case 0) Nov 21 22:26:45 Hippo smbd[5707]: trustee : S-1-22-2-100 Nov 21 22:26:45 Hippo smbd[5707]: aces: struct security_ace Nov 21 22:26:45 Hippo smbd[5707]: type : SEC_ACE_TYPE_ACCESS_ALLOWED (0) Nov 21 22:26:45 Hippo smbd[5707]: flags : 0x00 (0) Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_OBJECT_INHERIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_CONTAINER_INHERIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_NO_PROPAGATE_INHERIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_INHERIT_ONLY Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_INHERITED_ACE Nov 21 22:26:45 Hippo smbd[5707]: 0x00: SEC_ACE_FLAG_VALID_INHERIT (0) Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_SUCCESSFUL_ACCESS Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_ACE_FLAG_FAILED_ACCESS Nov 21 22:26:45 Hippo smbd[5707]: size : 0x0014 (20) Nov 21 22:26:45 Hippo smbd[5707]: access_mask : 0x0012019f (1180063) Nov 21 22:26:45 Hippo smbd[5707]: object : union security_ace_object_ctr(case 0) Nov 21 22:26:45 Hippo smbd[5707]: trustee : S-1-1-0 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.017467, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:3350(posix_fget_nt_acl) Nov 21 22:26:45 Hippo smbd[5707]: posix_fget_nt_acl: called for file temp2 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.017691, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/passdb/lookup_sid.c:1201(xid_to_sid) Nov 21 22:26:45 Hippo smbd[5707]: xid_to_sid: UID 1000 -> S-1-5-21-798852891-1520055758-2825886587-3000 from cache Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.017721, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/passdb/lookup_sid.c:1201(xid_to_sid) Nov 21 22:26:45 Hippo smbd[5707]: xid_to_sid: GID 100 -> S-0-0 from cache Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.017746, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/passdb/lookup_sid.c:1256(xid_to_sid) Nov 21 22:26:45 Hippo smbd[5707]: xid_to_sid: GID 100 -> S-1-22-2-100 fallback Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.017775, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:2692(canonicalise_acl) Nov 21 22:26:45 Hippo smbd[5707]: canonicalise_acl: Access ace entries before arrange : Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.017802, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:2705(canonicalise_acl) Nov 21 22:26:45 Hippo smbd[5707]: canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms rwx Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.017822, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:2705(canonicalise_acl) Nov 21 22:26:45 Hippo smbd[5707]: canon_ace index 1. Type = allow SID = S-1-22-2-100 gid 100 SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.017841, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:2705(canonicalise_acl) Nov 21 22:26:45 Hippo smbd[5707]: canon_ace index 2. Type = allow SID = S-1-5-21-798852891-1520055758-2825886587-3000 uid 1000 SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.017859, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:766(print_canon_ace_list) Nov 21 22:26:45 Hippo smbd[5707]: print_canon_ace_list: canonicalise_acl: ace entries after arrange Nov 21 22:26:45 Hippo smbd[5707]: canon_ace index 0. Type = allow SID = S-1-5-21-798852891-1520055758-2825886587-3000 uid 1000 SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx Nov 21 22:26:45 Hippo smbd[5707]: canon_ace index 1. Type = allow SID = S-1-22-2-100 gid 100 SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx Nov 21 22:26:45 Hippo smbd[5707]: canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms rwx Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.017920, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:1029(map_canon_ace_perms) Nov 21 22:26:45 Hippo smbd[5707]: map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.017948, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:1029(map_canon_ace_perms) Nov 21 22:26:45 Hippo smbd[5707]: map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.017977, 10, pid=5707, effective(1000, 100), real(1000, 0), class=acls] ../../source3/smbd/posix_acls.c:1029(map_canon_ace_perms) Nov 21 22:26:45 Hippo smbd[5707]: map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018002, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:180(smbd_check_access_rights_sd) Nov 21 22:26:45 Hippo smbd[5707]: smbd_check_access_rights_sd: File [temp2] requesting [0x40] returning [0x40] (NT_STATUS_OK) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018040, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:237(smbd_check_access_rights_sd) Nov 21 22:26:45 Hippo smbd[5707]: smbd_check_access_rights_sd: Overrode DELETE_ACCESS on file temp2/temp.txt Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018068, 2, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:1678(open_file) Nov 21 22:26:45 Hippo smbd[5707]: admin opened file temp2/temp.txt read=No write=No (numopen=2) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018114, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:487(dbwrap_watched_do_locked) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_do_locked: dbwrap_watched_do_locked_fn returned Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018143, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:550(dbwrap_watched_trigger_wakeup) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_trigger_wakeup: No one to wakeup Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018169, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/g_lock.c:916(g_lock_lock) Nov 21 22:26:45 Hippo smbd[5707]: g_lock_lock: status=NT_STATUS_OK, state.status=NT_STATUS_OK Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018199, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap.c:143(dbwrap_lock_order_lock) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_lock_order_lock: check lock order 1 for /var/cache/samba/locking.tdb Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018225, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap.c:128(debug_lock_order) Nov 21 22:26:45 Hippo smbd[5707]: lock order: 1:/var/cache/samba/locking.tdb 2: 3: 4: Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018260, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:943(get_share_mode_lock) Nov 21 22:26:45 Hippo smbd[5707]: get_share_mode_lock: Returning 56:27959:0 (data_refcount=1 key_refcount=1) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018293, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:2223(validate_oplock_types) Nov 21 22:26:45 Hippo smbd[5707]: validate_oplock_types: smbd:validate_oplock_types not set to yes Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018321, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/locking.c:952(find_delete_on_close_token) Nov 21 22:26:45 Hippo smbd[5707]: find_delete_on_close_token: name_hash = 0xf9801c3b Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018349, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:1741(share_conflict) Nov 21 22:26:45 Hippo smbd[5707]: share_conflict: existing access_mask = 0x0, existing share access = 0x7, access_mask = 0x10000, share_access = 0x7 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018376, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:1751(share_conflict) Nov 21 22:26:45 Hippo smbd[5707]: share_conflict: No conflict due to existing access_mask = 0x0 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018402, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:2017(open_mode_check) Nov 21 22:26:45 Hippo smbd[5707]: open_mode_check: No conflict due to share_mode_flags access Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018432, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:2067(share_mode_forall_entries) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_forall_entries: num_share_modes=0 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018462, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:2086(share_mode_forall_entries) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_forall_entries: num_share_entries=0, writeback=0 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018491, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/brlock.c:1889(brl_get_locks_readonly) Nov 21 22:26:45 Hippo smbd[5707]: seqnum=0, fsp->brlock_seqnum=0 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018546, 5, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/smbd/smb2_oplock.c:84(set_file_oplock) Nov 21 22:26:45 Hippo smbd[5707]: set_file_oplock: granted oplock on file temp2/temp.txt, 56:27959:0/18, tv_sec = 637c4f85, tv_usec = 3529 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018574, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:2818(delay_for_oplock) Nov 21 22:26:45 Hippo smbd[5707]: delay_for_oplock: oplock type 0x0 on file temp2/temp.txt Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018605, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:1820(set_share_mode) Nov 21 22:26:45 Hippo smbd[5707]: set_share_mode: num_share_modes=0 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018643, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:371(share_mode_entry_put) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_entry_put: share_mode_entry: Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018680, 1, pid=5707, effective(1000, 100), real(1000, 0), class=rpc_parse] ../../source3/locking/share_mode_lock.c:372(share_mode_entry_put) Nov 21 22:26:45 Hippo smbd[5707]: discard_const_p(void, e): struct share_mode_entry Nov 21 22:26:45 Hippo smbd[5707]: pid: struct server_id Nov 21 22:26:45 Hippo smbd[5707]: pid : 0x000000000000164b (5707) Nov 21 22:26:45 Hippo smbd[5707]: task_id : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: vnn : 0xffffffff (4294967295) Nov 21 22:26:45 Hippo smbd[5707]: unique_id : 0xb0900bc0689cc508 (-5724062205371169528) Nov 21 22:26:45 Hippo smbd[5707]: op_mid : 0x000000000000006a (106) Nov 21 22:26:45 Hippo smbd[5707]: op_type : 0x0000 (0) Nov 21 22:26:45 Hippo smbd[5707]: client_guid : 00000000-0000-0000-0000-000000000000 Nov 21 22:26:45 Hippo smbd[5707]: lease_key: struct smb2_lease_key Nov 21 22:26:45 Hippo smbd[5707]: data: ARRAY(2) Nov 21 22:26:45 Hippo smbd[5707]: data : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: data : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: access_mask : 0x00010000 (65536) Nov 21 22:26:45 Hippo smbd[5707]: share_access : 0x00000007 (7) Nov 21 22:26:45 Hippo smbd[5707]: private_options : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: time : Mon Nov 21 22:26:45 2022 CST.13609 Nov 21 22:26:45 Hippo smbd[5707]: share_file_id : 0x0000000000000012 (18) Nov 21 22:26:45 Hippo smbd[5707]: uid : 0x000003e8 (1000) Nov 21 22:26:45 Hippo smbd[5707]: flags : 0x0000 (0) Nov 21 22:26:45 Hippo smbd[5707]: name_hash : 0xf9801c3b (4185922619) Nov 21 22:26:45 Hippo smbd[5707]: stale : 0x00 (0) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.018990, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:1864(set_share_mode) Nov 21 22:26:45 Hippo smbd[5707]: set_share_mode: idx=0, found=0 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019020, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:1892(set_share_mode) Nov 21 22:26:45 Hippo smbd[5707]: set_share_mode: dbufs[0]=(0x7fffce5e8040, 132) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019054, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:487(dbwrap_watched_do_locked) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_do_locked: dbwrap_watched_do_locked_fn returned Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019080, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:550(dbwrap_watched_trigger_wakeup) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_trigger_wakeup: No one to wakeup Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019108, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:649(share_mode_data_store) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_data_store: Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019135, 1, pid=5707, effective(1000, 100), real(1000, 0), class=rpc_parse] ../../source3/locking/share_mode_lock.c:650(share_mode_data_store) Nov 21 22:26:45 Hippo smbd[5707]: d: struct share_mode_data Nov 21 22:26:45 Hippo smbd[5707]: unique_content_epoch : 0x84985839a86ac25b (-8892260459581095333) Nov 21 22:26:45 Hippo smbd[5707]: flags : 0x01e0 (480) Nov 21 22:26:45 Hippo smbd[5707]: 1: SHARE_MODE_SHARE_DELETE Nov 21 22:26:45 Hippo smbd[5707]: 1: SHARE_MODE_SHARE_WRITE Nov 21 22:26:45 Hippo smbd[5707]: 1: SHARE_MODE_SHARE_READ Nov 21 22:26:45 Hippo smbd[5707]: 1: SHARE_MODE_ACCESS_DELETE Nov 21 22:26:45 Hippo smbd[5707]: 0: SHARE_MODE_ACCESS_WRITE Nov 21 22:26:45 Hippo smbd[5707]: 0: SHARE_MODE_ACCESS_READ Nov 21 22:26:45 Hippo smbd[5707]: 0: SHARE_MODE_LEASE_HANDLE Nov 21 22:26:45 Hippo smbd[5707]: 0: SHARE_MODE_LEASE_WRITE Nov 21 22:26:45 Hippo smbd[5707]: 0: SHARE_MODE_LEASE_READ Nov 21 22:26:45 Hippo smbd[5707]: servicepath : * Nov 21 22:26:45 Hippo smbd[5707]: servicepath : '/mnt/user/incoming' Nov 21 22:26:45 Hippo smbd[5707]: base_name : * Nov 21 22:26:45 Hippo smbd[5707]: base_name : 'temp2/temp.txt' Nov 21 22:26:45 Hippo smbd[5707]: stream_name : NULL Nov 21 22:26:45 Hippo smbd[5707]: num_delete_tokens : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: delete_tokens: ARRAY(0) Nov 21 22:26:45 Hippo smbd[5707]: old_write_time : Mon Nov 21 22:25:37 2022 CST Nov 21 22:26:45 Hippo smbd[5707]: changed_write_time : NTTIME(0) Nov 21 22:26:45 Hippo smbd[5707]: fresh : 0x01 (1) Nov 21 22:26:45 Hippo smbd[5707]: modified : 0x01 (1) Nov 21 22:26:45 Hippo smbd[5707]: id: struct file_id Nov 21 22:26:45 Hippo smbd[5707]: devid : 0x0000000000000038 (56) Nov 21 22:26:45 Hippo smbd[5707]: inode : 0x0000000000006d37 (27959) Nov 21 22:26:45 Hippo smbd[5707]: extid : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019505, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:487(dbwrap_watched_do_locked) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_do_locked: dbwrap_watched_do_locked_fn returned Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019542, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:550(dbwrap_watched_trigger_wakeup) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_trigger_wakeup: No one to wakeup Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019575, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:507(dbwrap_watched_record_prepare_wakeup) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_record_prepare_wakeup: No watchers Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019604, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:487(dbwrap_watched_do_locked) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_do_locked: dbwrap_watched_do_locked_fn returned Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019638, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:550(dbwrap_watched_trigger_wakeup) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_trigger_wakeup: No one to wakeup Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019665, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap.c:175(dbwrap_lock_order_unlock) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_lock_order_unlock: release lock order 1 for /var/cache/samba/locking.tdb Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019684, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:168(share_mode_memcache_store) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_memcache_store: stored entry for file temp2/temp.txt epoch 84985839a86ac25d key 56:27959:0 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019705, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:6188(create_file_unixpath) Nov 21 22:26:45 Hippo smbd[5707]: create_file_unixpath: info=1 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019721, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:6384(create_file_default) Nov 21 22:26:45 Hippo smbd[5707]: create_file: info=1 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019737, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_create.c:1396(smbd_smb2_create_after_exec) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_create_send: response construction phase Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019753, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:715(fdos_mode) Nov 21 22:26:45 Hippo smbd[5707]: fdos_mode: temp2/temp.txt Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019846, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:260(parse_dos_attribute_blob) Nov 21 22:26:45 Hippo smbd[5707]: parse_dos_attribute_blob: temp2/temp.txt attr = Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019869, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:328(parse_dos_attribute_blob) Nov 21 22:26:45 Hippo smbd[5707]: parse_dos_attribute_blob: file [temp2/temp.txt] creation time [Mon Nov 21 22:25:37 2022 CST] Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019890, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:68(dos_mode_debug_print) Nov 21 22:26:45 Hippo smbd[5707]: dos_mode_debug_print: parse_dos_attribute_blob returning (0x20): "a" Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019907, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:68(dos_mode_debug_print) Nov 21 22:26:45 Hippo smbd[5707]: dos_mode_debug_print: fdos_mode returning (0x20): "a" Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019927, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_create.c:1633(smbd_smb2_create_finish) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_create_finish: temp2/temp.txt - fnum 3140914304 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019954, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_server.c:3841(smbd_smb2_request_done_ex) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_request_done_ex: mid [106] idx[1] status[NT_STATUS_OK] body[88] dyn[yes:0] at ../../source3/smbd/smb2_create.c:410 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019974, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2_credits] ../../source3/smbd/smb2_server.c:969(smb2_set_operation_credit) Nov 21 22:26:45 Hippo smbd[5707]: smb2_set_operation_credit: smb2_set_operation_credit: requested 2, charge 1, granted 2, current possible/max 7489/8192, total granted/max/low/range 705/8192/107/705 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.019999, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../libcli/smb/smb2_signing.c:657(smb2_signing_sign_pdu) Nov 21 22:26:45 Hippo smbd[5707]: signed SMB2 message (sign_algo_id=0) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.020074, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/files.c:1800(file_free) Nov 21 22:26:45 Hippo smbd[5707]: file_free: freed files structure 0 (2 used) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.043615, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_server.c:4995(smbd_smb2_io_handler) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_request idx[1] of 5 vectors Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.043661, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2_credits] ../../source3/smbd/smb2_server.c:712(smb2_validate_sequence_number) Nov 21 22:26:45 Hippo smbd[5707]: smb2_validate_sequence_number: smb2_validate_sequence_number: clearing id 107 (position 107) from bitmap Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.043683, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_server.c:2980(smbd_smb2_request_dispatch) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_request_dispatch: opcode[SMB2_OP_SETINFO] mid = 107 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.043710, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/uid.c:327(change_to_user_impersonate) Nov 21 22:26:45 Hippo smbd[5707]: change_to_user_impersonate: Skipping user change - already user Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.043742, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/uid.c:294(print_impersonation_info) Nov 21 22:26:45 Hippo smbd[5707]: print_impersonation_info: Impersonated user: uid=(1000,1000), gid=(0,100), cwd=[/mnt/user/incoming] Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.043765, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2_credits] ../../source3/smbd/smb2_server.c:2647(smbd_smb2_request_verify_creditcharge) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_request_verify_creditcharge: mid 107, CreditCharge: 1, NeededCharge: 1 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.043785, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_setinfo.c:392(smbd_smb2_setinfo_send) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_setinfo_send: temp2/temp.txt - fnum 3140914304 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.043808, 3, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/smb2_trans2.c:6834(smbd_do_setfilepathinfo) Nov 21 22:26:45 Hippo smbd[5707]: smbd_do_setfilepathinfo: temp2/temp.txt (fnum 3140914304) info_level=1013 totdata=1 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.043826, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:715(fdos_mode) Nov 21 22:26:45 Hippo smbd[5707]: fdos_mode: temp2/temp.txt Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044008, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:260(parse_dos_attribute_blob) Nov 21 22:26:45 Hippo smbd[5707]: parse_dos_attribute_blob: temp2/temp.txt attr = Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044043, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:328(parse_dos_attribute_blob) Nov 21 22:26:45 Hippo smbd[5707]: parse_dos_attribute_blob: file [temp2/temp.txt] creation time [Mon Nov 21 22:25:37 2022 CST] Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044069, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:68(dos_mode_debug_print) Nov 21 22:26:45 Hippo smbd[5707]: dos_mode_debug_print: parse_dos_attribute_blob returning (0x20): "a" Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044087, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:68(dos_mode_debug_print) Nov 21 22:26:45 Hippo smbd[5707]: dos_mode_debug_print: fdos_mode returning (0x20): "a" Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044103, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/smb2_trans2.c:4725(smb_set_file_disposition_info) Nov 21 22:26:45 Hippo smbd[5707]: smb_set_file_disposition_info: file temp2/temp.txt, dosmode = 32, delete_on_close = 1 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044120, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/locking.c:918(set_delete_on_close) Nov 21 22:26:45 Hippo smbd[5707]: set_delete_on_close: Adding delete on close flag for fnum 3140914304, file temp2/temp.txt Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044148, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:487(dbwrap_watched_do_locked) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_do_locked: dbwrap_watched_do_locked_fn returned Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044167, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:550(dbwrap_watched_trigger_wakeup) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_trigger_wakeup: No one to wakeup Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044183, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/g_lock.c:916(g_lock_lock) Nov 21 22:26:45 Hippo smbd[5707]: g_lock_lock: status=NT_STATUS_OK, state.status=NT_STATUS_OK Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044199, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap.c:143(dbwrap_lock_order_lock) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_lock_order_lock: check lock order 1 for /var/cache/samba/locking.tdb Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044225, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap.c:128(debug_lock_order) Nov 21 22:26:45 Hippo smbd[5707]: lock order: 1:/var/cache/samba/locking.tdb 2: 3: 4: Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044248, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:344(share_mode_memcache_fetch) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_memcache_fetch: fetched entry for file temp2/temp.txt epoch 84985839a86ac25d key 56:27959:0 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044269, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:943(get_share_mode_lock) Nov 21 22:26:45 Hippo smbd[5707]: get_share_mode_lock: Returning 56:27959:0 (data_refcount=1 key_refcount=1) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044293, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:2067(share_mode_forall_entries) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_forall_entries: num_share_modes=1 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044316, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:1975(share_mode_for_one_entry) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_for_one_entry: entry[0]: Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044333, 1, pid=5707, effective(1000, 100), real(1000, 0), class=rpc_parse] ../../source3/locking/share_mode_lock.c:1976(share_mode_for_one_entry) Nov 21 22:26:45 Hippo smbd[5707]: &e: struct share_mode_entry Nov 21 22:26:45 Hippo smbd[5707]: pid: struct server_id Nov 21 22:26:45 Hippo smbd[5707]: pid : 0x000000000000164b (5707) Nov 21 22:26:45 Hippo smbd[5707]: task_id : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: vnn : 0xffffffff (4294967295) Nov 21 22:26:45 Hippo smbd[5707]: unique_id : 0xb0900bc0689cc508 (-5724062205371169528) Nov 21 22:26:45 Hippo smbd[5707]: op_mid : 0x000000000000006a (106) Nov 21 22:26:45 Hippo smbd[5707]: op_type : 0x0000 (0) Nov 21 22:26:45 Hippo smbd[5707]: client_guid : 00000000-0000-0000-0000-000000000000 Nov 21 22:26:45 Hippo smbd[5707]: lease_key: struct smb2_lease_key Nov 21 22:26:45 Hippo smbd[5707]: data: ARRAY(2) Nov 21 22:26:45 Hippo smbd[5707]: data : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: data : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: access_mask : 0x00010000 (65536) Nov 21 22:26:45 Hippo smbd[5707]: share_access : 0x00000007 (7) Nov 21 22:26:45 Hippo smbd[5707]: private_options : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: time : Mon Nov 21 22:26:45 2022 CST.13609 Nov 21 22:26:45 Hippo smbd[5707]: share_file_id : 0x0000000000000012 (18) Nov 21 22:26:45 Hippo smbd[5707]: uid : 0x000003e8 (1000) Nov 21 22:26:45 Hippo smbd[5707]: flags : 0x0000 (0) Nov 21 22:26:45 Hippo smbd[5707]: name_hash : 0xf9801c3b (4185922619) Nov 21 22:26:45 Hippo smbd[5707]: stale : 0x00 (0) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044514, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:1984(share_mode_for_one_entry) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_for_one_entry: entry[0]: modified=0, e.stale=0 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044533, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:2086(share_mode_forall_entries) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_forall_entries: num_share_entries=1, writeback=0 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044550, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:649(share_mode_data_store) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_data_store: Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.044567, 1, pid=5707, effective(1000, 100), real(1000, 0), class=rpc_parse] ../../source3/locking/share_mode_lock.c:650(share_mode_data_store) Nov 21 22:26:45 Hippo smbd[5707]: d: struct share_mode_data Nov 21 22:26:45 Hippo smbd[5707]: unique_content_epoch : 0x84985839a86ac25d (-8892260459581095331) Nov 21 22:26:45 Hippo smbd[5707]: flags : 0x01e0 (480) Nov 21 22:26:45 Hippo smbd[5707]: 1: SHARE_MODE_SHARE_DELETE Nov 21 22:26:45 Hippo smbd[5707]: 1: SHARE_MODE_SHARE_WRITE Nov 21 22:26:45 Hippo smbd[5707]: 1: SHARE_MODE_SHARE_READ Nov 21 22:26:45 Hippo smbd[5707]: 1: SHARE_MODE_ACCESS_DELETE Nov 21 22:26:45 Hippo smbd[5707]: 0: SHARE_MODE_ACCESS_WRITE Nov 21 22:26:45 Hippo smbd[5707]: 0: SHARE_MODE_ACCESS_READ Nov 21 22:26:45 Hippo smbd[5707]: 0: SHARE_MODE_LEASE_HANDLE Nov 21 22:26:45 Hippo smbd[5707]: 0: SHARE_MODE_LEASE_WRITE Nov 21 22:26:45 Hippo smbd[5707]: 0: SHARE_MODE_LEASE_READ Nov 21 22:26:45 Hippo smbd[5707]: servicepath : * Nov 21 22:26:45 Hippo smbd[5707]: servicepath : '/mnt/user/incoming' Nov 21 22:26:45 Hippo smbd[5707]: base_name : * Nov 21 22:26:45 Hippo smbd[5707]: base_name : 'temp2/temp.txt' Nov 21 22:26:45 Hippo smbd[5707]: stream_name : NULL Nov 21 22:26:45 Hippo smbd[5707]: num_delete_tokens : 0x00000001 (1) Nov 21 22:26:45 Hippo smbd[5707]: delete_tokens: ARRAY(1) Nov 21 22:26:45 Hippo smbd[5707]: delete_tokens: struct delete_token Nov 21 22:26:45 Hippo smbd[5707]: name_hash : 0xf9801c3b (4185922619) Nov 21 22:26:45 Hippo smbd[5707]: delete_nt_token : * Nov 21 22:26:45 Hippo smbd[5707]: delete_nt_token: struct security_token Nov 21 22:26:45 Hippo smbd[5707]: num_sids : 0x0000000a (10) Nov 21 22:26:45 Hippo smbd[5707]: sids: ARRAY(10) Nov 21 22:26:45 Hippo smbd[5707]: sids : S-1-5-21-798852891-1520055758-2825886587-3000 Nov 21 22:26:45 Hippo smbd[5707]: sids : S-1-5-21-798852891-1520055758-2825886587-513 Nov 21 22:26:45 Hippo smbd[5707]: sids : S-1-22-2-100 Nov 21 22:26:45 Hippo smbd[5707]: sids : S-1-1-0 Nov 21 22:26:45 Hippo smbd[5707]: sids : S-1-5-2 Nov 21 22:26:45 Hippo smbd[5707]: sids : S-1-5-11 Nov 21 22:26:45 Hippo smbd[5707]: sids : S-1-22-1-1000 Nov 21 22:26:45 Hippo smbd[5707]: sids : S-1-22-2-3003 Nov 21 22:26:45 Hippo smbd[5707]: sids : S-1-22-2-3004 Nov 21 22:26:45 Hippo smbd[5707]: sids : S-1-22-2-3006 Nov 21 22:26:45 Hippo smbd[5707]: privilege_mask : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_MACHINE_ACCOUNT_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_PRINT_OPERATOR_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_ADD_USERS_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_DISK_OPERATOR_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_REMOTE_SHUTDOWN_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_BACKUP_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_RESTORE_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_TAKE_OWNERSHIP_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_INCREASE_QUOTA_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_SECURITY_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_LOAD_DRIVER_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_SYSTEM_PROFILE_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_SYSTEMTIME_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_PROFILE_SINGLE_PROCESS_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_INCREASE_BASE_PRIORITY_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_CREATE_PAGEFILE_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_SHUTDOWN_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_DEBUG_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_SYSTEM_ENVIRONMENT_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_CHANGE_NOTIFY_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_UNDOCK_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_ENABLE_DELEGATION_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_MANAGE_VOLUME_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_IMPERSONATE_BIT Nov 21 22:26:45 Hippo smbd[5707]: 0: SEC_PRIV_CREATE_GLOBAL_BIT Nov 21 22:26:45 Hippo smbd[5707]: rights_mask : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: 0: LSA_POLICY_MODE_INTERACTIVE Nov 21 22:26:45 Hippo smbd[5707]: 0: LSA_POLICY_MODE_NETWORK Nov 21 22:26:45 Hippo smbd[5707]: 0: LSA_POLICY_MODE_BATCH Nov 21 22:26:45 Hippo smbd[5707]: 0: LSA_POLICY_MODE_SERVICE Nov 21 22:26:45 Hippo smbd[5707]: 0: LSA_POLICY_MODE_PROXY Nov 21 22:26:45 Hippo smbd[5707]: 0: LSA_POLICY_MODE_DENY_INTERACTIVE Nov 21 22:26:45 Hippo smbd[5707]: 0: LSA_POLICY_MODE_DENY_NETWORK Nov 21 22:26:45 Hippo smbd[5707]: 0: LSA_POLICY_MODE_DENY_BATCH Nov 21 22:26:45 Hippo smbd[5707]: 0: LSA_POLICY_MODE_DENY_SERVICE Nov 21 22:26:45 Hippo smbd[5707]: 0: LSA_POLICY_MODE_REMOTE_INTERACTIVE Nov 21 22:26:45 Hippo smbd[5707]: 0: LSA_POLICY_MODE_DENY_REMOTE_INTERACTIVE Nov 21 22:26:45 Hippo smbd[5707]: 0x00: LSA_POLICY_MODE_ALL (0) Nov 21 22:26:45 Hippo smbd[5707]: 0x00: LSA_POLICY_MODE_ALL_NT4 (0) Nov 21 22:26:45 Hippo smbd[5707]: delete_token : * Nov 21 22:26:45 Hippo smbd[5707]: delete_token: struct security_unix_token Nov 21 22:26:45 Hippo smbd[5707]: uid : 0x00000000000003e8 (1000) Nov 21 22:26:45 Hippo smbd[5707]: gid : 0x0000000000000064 (100) Nov 21 22:26:45 Hippo smbd[5707]: ngroups : 0x00000004 (4) Nov 21 22:26:45 Hippo smbd[5707]: groups: ARRAY(4) Nov 21 22:26:45 Hippo smbd[5707]: groups : 0x0000000000000064 (100) Nov 21 22:26:45 Hippo smbd[5707]: groups : 0x0000000000000bbb (3003) Nov 21 22:26:45 Hippo smbd[5707]: groups : 0x0000000000000bbc (3004) Nov 21 22:26:45 Hippo smbd[5707]: groups : 0x0000000000000bbe (3006) Nov 21 22:26:45 Hippo smbd[5707]: old_write_time : Mon Nov 21 22:25:37 2022 CST Nov 21 22:26:45 Hippo smbd[5707]: changed_write_time : NTTIME(0) Nov 21 22:26:45 Hippo smbd[5707]: fresh : 0x00 (0) Nov 21 22:26:45 Hippo smbd[5707]: modified : 0x01 (1) Nov 21 22:26:45 Hippo smbd[5707]: id: struct file_id Nov 21 22:26:45 Hippo smbd[5707]: devid : 0x0000000000000038 (56) Nov 21 22:26:45 Hippo smbd[5707]: inode : 0x0000000000006d37 (27959) Nov 21 22:26:45 Hippo smbd[5707]: extid : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.045301, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:487(dbwrap_watched_do_locked) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_do_locked: dbwrap_watched_do_locked_fn returned Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.045323, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:550(dbwrap_watched_trigger_wakeup) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_trigger_wakeup: No one to wakeup Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.045344, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:507(dbwrap_watched_record_prepare_wakeup) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_record_prepare_wakeup: No watchers Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.045363, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:487(dbwrap_watched_do_locked) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_do_locked: dbwrap_watched_do_locked_fn returned Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.045380, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:550(dbwrap_watched_trigger_wakeup) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_trigger_wakeup: No one to wakeup Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.045396, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap.c:175(dbwrap_lock_order_unlock) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_lock_order_unlock: release lock order 1 for /var/cache/samba/locking.tdb Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.045413, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:168(share_mode_memcache_store) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_memcache_store: stored entry for file temp2/temp.txt epoch 84985839a86ac261 key 56:27959:0 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.045436, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_server.c:3841(smbd_smb2_request_done_ex) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_request_done_ex: mid [107] idx[1] status[NT_STATUS_OK] body[2] dyn[no:0] at ../../source3/smbd/smb2_setinfo.c:159 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.045456, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2_credits] ../../source3/smbd/smb2_server.c:969(smb2_set_operation_credit) Nov 21 22:26:45 Hippo smbd[5707]: smb2_set_operation_credit: smb2_set_operation_credit: requested 2, charge 1, granted 2, current possible/max 7488/8192, total granted/max/low/range 706/8192/108/706 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.045478, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../libcli/smb/smb2_signing.c:657(smb2_signing_sign_pdu) Nov 21 22:26:45 Hippo smbd[5707]: signed SMB2 message (sign_algo_id=0) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.052731, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_server.c:4995(smbd_smb2_io_handler) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_request idx[1] of 5 vectors Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.052766, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2_credits] ../../source3/smbd/smb2_server.c:712(smb2_validate_sequence_number) Nov 21 22:26:45 Hippo smbd[5707]: smb2_validate_sequence_number: smb2_validate_sequence_number: clearing id 108 (position 108) from bitmap Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.052786, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_server.c:2980(smbd_smb2_request_dispatch) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_request_dispatch: opcode[SMB2_OP_CLOSE] mid = 108 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.052810, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/uid.c:327(change_to_user_impersonate) Nov 21 22:26:45 Hippo smbd[5707]: change_to_user_impersonate: Skipping user change - already user Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.052844, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/uid.c:294(print_impersonation_info) Nov 21 22:26:45 Hippo smbd[5707]: print_impersonation_info: Impersonated user: uid=(1000,1000), gid=(0,100), cwd=[/mnt/user/incoming] Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.052867, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_close.c:211(smbd_smb2_close) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_close: temp2/temp.txt - fnum 3140914304 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.052887, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:715(fdos_mode) Nov 21 22:26:45 Hippo smbd[5707]: fdos_mode: temp2/temp.txt Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053017, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:260(parse_dos_attribute_blob) Nov 21 22:26:45 Hippo smbd[5707]: parse_dos_attribute_blob: temp2/temp.txt attr = Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053051, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:328(parse_dos_attribute_blob) Nov 21 22:26:45 Hippo smbd[5707]: parse_dos_attribute_blob: file [temp2/temp.txt] creation time [Mon Nov 21 22:25:37 2022 CST] Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053078, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:68(dos_mode_debug_print) Nov 21 22:26:45 Hippo smbd[5707]: dos_mode_debug_print: parse_dos_attribute_blob returning (0x20): "a" Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053095, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/dosmode.c:68(dos_mode_debug_print) Nov 21 22:26:45 Hippo smbd[5707]: dos_mode_debug_print: fdos_mode returning (0x20): "a" Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053120, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:487(dbwrap_watched_do_locked) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_do_locked: dbwrap_watched_do_locked_fn returned Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053138, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:550(dbwrap_watched_trigger_wakeup) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_trigger_wakeup: No one to wakeup Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053154, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/g_lock.c:916(g_lock_lock) Nov 21 22:26:45 Hippo smbd[5707]: g_lock_lock: status=NT_STATUS_OK, state.status=NT_STATUS_OK Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053170, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap.c:143(dbwrap_lock_order_lock) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_lock_order_lock: check lock order 1 for /var/cache/samba/locking.tdb Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053185, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap.c:128(debug_lock_order) Nov 21 22:26:45 Hippo smbd[5707]: lock order: 1:/var/cache/samba/locking.tdb 2: 3: 4: Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053205, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:344(share_mode_memcache_fetch) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_memcache_fetch: fetched entry for file temp2/temp.txt epoch 84985839a86ac261 key 56:27959:0 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053225, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:943(get_share_mode_lock) Nov 21 22:26:45 Hippo smbd[5707]: get_share_mode_lock: Returning 56:27959:0 (data_refcount=1 key_refcount=1) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053243, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/locking.c:952(find_delete_on_close_token) Nov 21 22:26:45 Hippo smbd[5707]: find_delete_on_close_token: name_hash = 0xf9801c3b Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053259, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/locking.c:957(find_delete_on_close_token) Nov 21 22:26:45 Hippo smbd[5707]: find_delete_on_close_token: dt->name_hash = 0xf9801c3b Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053277, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:2067(share_mode_forall_entries) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_forall_entries: num_share_modes=1 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053296, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:1975(share_mode_for_one_entry) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_for_one_entry: entry[0]: Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053313, 1, pid=5707, effective(1000, 100), real(1000, 0), class=rpc_parse] ../../source3/locking/share_mode_lock.c:1976(share_mode_for_one_entry) Nov 21 22:26:45 Hippo smbd[5707]: &e: struct share_mode_entry Nov 21 22:26:45 Hippo smbd[5707]: pid: struct server_id Nov 21 22:26:45 Hippo smbd[5707]: pid : 0x000000000000164b (5707) Nov 21 22:26:45 Hippo smbd[5707]: task_id : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: vnn : 0xffffffff (4294967295) Nov 21 22:26:45 Hippo smbd[5707]: unique_id : 0xb0900bc0689cc508 (-5724062205371169528) Nov 21 22:26:45 Hippo smbd[5707]: op_mid : 0x000000000000006a (106) Nov 21 22:26:45 Hippo smbd[5707]: op_type : 0x0000 (0) Nov 21 22:26:45 Hippo smbd[5707]: client_guid : 00000000-0000-0000-0000-000000000000 Nov 21 22:26:45 Hippo smbd[5707]: lease_key: struct smb2_lease_key Nov 21 22:26:45 Hippo smbd[5707]: data: ARRAY(2) Nov 21 22:26:45 Hippo smbd[5707]: data : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: data : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: access_mask : 0x00010000 (65536) Nov 21 22:26:45 Hippo smbd[5707]: share_access : 0x00000007 (7) Nov 21 22:26:45 Hippo smbd[5707]: private_options : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: time : Mon Nov 21 22:26:45 2022 CST.13609 Nov 21 22:26:45 Hippo smbd[5707]: share_file_id : 0x0000000000000012 (18) Nov 21 22:26:45 Hippo smbd[5707]: uid : 0x000003e8 (1000) Nov 21 22:26:45 Hippo smbd[5707]: flags : 0x0000 (0) Nov 21 22:26:45 Hippo smbd[5707]: name_hash : 0xf9801c3b (4185922619) Nov 21 22:26:45 Hippo smbd[5707]: stale : 0x00 (0) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053485, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:1984(share_mode_for_one_entry) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_for_one_entry: entry[0]: modified=0, e.stale=0 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053503, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:2086(share_mode_forall_entries) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_forall_entries: num_share_entries=1, writeback=0 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053519, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/close.c:377(close_remove_share_mode) Nov 21 22:26:45 Hippo smbd[5707]: close_remove_share_mode: file temp2/temp.txt. Delete on close was set - deleting file. Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053535, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/locking.c:952(find_delete_on_close_token) Nov 21 22:26:45 Hippo smbd[5707]: find_delete_on_close_token: name_hash = 0xf9801c3b Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053551, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/locking.c:957(find_delete_on_close_token) Nov 21 22:26:45 Hippo smbd[5707]: find_delete_on_close_token: dt->name_hash = 0xf9801c3b Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053574, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/files.c:559(openat_pathref_fsp) Nov 21 22:26:45 Hippo smbd[5707]: openat_pathref_fsp: smb_fname [temp2] Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053600, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/files.c:459(openat_pathref_fullname) Nov 21 22:26:45 Hippo smbd[5707]: openat_pathref_fullname: smb_fname [temp2] Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053618, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/files.c:76(fsp_new) Nov 21 22:26:45 Hippo smbd[5707]: fsp_new: allocated files structure (3 used) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053650, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/files.c:2005(file_name_hash) Nov 21 22:26:45 Hippo smbd[5707]: file_name_hash: /mnt/user/incoming/temp2 hash 0x4715f3bd Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053674, 10, pid=5707, effective(1000, 100), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1151(check_reduced_name) Nov 21 22:26:45 Hippo smbd[5707]: check_reduced_name: check_reduced_name [.] [/mnt/user/incoming] Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053695, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/modules/vfs_widelinks.c:293(widelinks_realpath) Nov 21 22:26:45 Hippo smbd[5707]: widelinks_realpath: realpath |.| -> |/mnt/user/incoming/.| -> |/mnt/user/incoming| Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053713, 10, pid=5707, effective(1000, 100), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1216(check_reduced_name) Nov 21 22:26:45 Hippo smbd[5707]: check_reduced_name realpath [.] -> [/mnt/user/incoming] Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053732, 5, pid=5707, effective(1000, 100), real(1000, 0), class=vfs] ../../source3/smbd/vfs.c:1334(check_reduced_name) Nov 21 22:26:45 Hippo smbd[5707]: check_reduced_name: . reduced to /mnt/user/incoming Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053757, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/open.c:975(fd_openat) Nov 21 22:26:45 Hippo smbd[5707]: fd_openat: name temp2, flags = 04000 mode = 00, fd = 30 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053776, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/files.c:528(openat_pathref_fullname) Nov 21 22:26:45 Hippo smbd[5707]: openat_pathref_fullname: fsp [temp2]: OK Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.053974, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/files.c:1800(file_free) Nov 21 22:26:45 Hippo smbd[5707]: file_free: freed files structure 0 (2 used) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054017, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:2196(share_mode_entry_do) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_entry_do: num_share_modes=1 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054040, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:1762(share_mode_entry_find) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_entry_find: left=0, right=0, middle=0, middle_ptr=0x55a39897ac14 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054061, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:2212(share_mode_entry_do) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_entry_do: entry[0]: Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054079, 1, pid=5707, effective(1000, 100), real(1000, 0), class=rpc_parse] ../../source3/locking/share_mode_lock.c:2213(share_mode_entry_do) Nov 21 22:26:45 Hippo smbd[5707]: &e: struct share_mode_entry Nov 21 22:26:45 Hippo smbd[5707]: pid: struct server_id Nov 21 22:26:45 Hippo smbd[5707]: pid : 0x000000000000164b (5707) Nov 21 22:26:45 Hippo smbd[5707]: task_id : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: vnn : 0xffffffff (4294967295) Nov 21 22:26:45 Hippo smbd[5707]: unique_id : 0xb0900bc0689cc508 (-5724062205371169528) Nov 21 22:26:45 Hippo smbd[5707]: op_mid : 0x000000000000006a (106) Nov 21 22:26:45 Hippo smbd[5707]: op_type : 0x0000 (0) Nov 21 22:26:45 Hippo smbd[5707]: client_guid : 00000000-0000-0000-0000-000000000000 Nov 21 22:26:45 Hippo smbd[5707]: lease_key: struct smb2_lease_key Nov 21 22:26:45 Hippo smbd[5707]: data: ARRAY(2) Nov 21 22:26:45 Hippo smbd[5707]: data : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: data : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: access_mask : 0x00010000 (65536) Nov 21 22:26:45 Hippo smbd[5707]: share_access : 0x00000007 (7) Nov 21 22:26:45 Hippo smbd[5707]: private_options : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: time : Mon Nov 21 22:26:45 2022 CST.13609 Nov 21 22:26:45 Hippo smbd[5707]: share_file_id : 0x0000000000000012 (18) Nov 21 22:26:45 Hippo smbd[5707]: uid : 0x000003e8 (1000) Nov 21 22:26:45 Hippo smbd[5707]: flags : 0x0000 (0) Nov 21 22:26:45 Hippo smbd[5707]: name_hash : 0xf9801c3b (4185922619) Nov 21 22:26:45 Hippo smbd[5707]: stale : 0x00 (0) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054250, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:2218(share_mode_entry_do) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_entry_do: entry[0]: modified=0, e.stale=1 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054268, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:2252(share_mode_entry_do) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_entry_do: share_mode_entry: Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054285, 1, pid=5707, effective(1000, 100), real(1000, 0), class=rpc_parse] ../../source3/locking/share_mode_lock.c:2253(share_mode_entry_do) Nov 21 22:26:45 Hippo smbd[5707]: &e: struct share_mode_entry Nov 21 22:26:45 Hippo smbd[5707]: pid: struct server_id Nov 21 22:26:45 Hippo smbd[5707]: pid : 0x000000000000164b (5707) Nov 21 22:26:45 Hippo smbd[5707]: task_id : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: vnn : 0xffffffff (4294967295) Nov 21 22:26:45 Hippo smbd[5707]: unique_id : 0xb0900bc0689cc508 (-5724062205371169528) Nov 21 22:26:45 Hippo smbd[5707]: op_mid : 0x000000000000006a (106) Nov 21 22:26:45 Hippo smbd[5707]: op_type : 0x0000 (0) Nov 21 22:26:45 Hippo smbd[5707]: client_guid : 00000000-0000-0000-0000-000000000000 Nov 21 22:26:45 Hippo smbd[5707]: lease_key: struct smb2_lease_key Nov 21 22:26:45 Hippo smbd[5707]: data: ARRAY(2) Nov 21 22:26:45 Hippo smbd[5707]: data : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: data : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: access_mask : 0x00010000 (65536) Nov 21 22:26:45 Hippo smbd[5707]: share_access : 0x00000007 (7) Nov 21 22:26:45 Hippo smbd[5707]: private_options : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: time : Mon Nov 21 22:26:45 2022 CST.13609 Nov 21 22:26:45 Hippo smbd[5707]: share_file_id : 0x0000000000000012 (18) Nov 21 22:26:45 Hippo smbd[5707]: uid : 0x000003e8 (1000) Nov 21 22:26:45 Hippo smbd[5707]: flags : 0x0000 (0) Nov 21 22:26:45 Hippo smbd[5707]: name_hash : 0xf9801c3b (4185922619) Nov 21 22:26:45 Hippo smbd[5707]: stale : 0x01 (1) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054467, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:487(dbwrap_watched_do_locked) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_do_locked: dbwrap_watched_do_locked_fn returned Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054487, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:550(dbwrap_watched_trigger_wakeup) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_trigger_wakeup: No one to wakeup Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054505, 10, pid=5707, effective(1000, 100), real(1000, 0), class=locking] ../../source3/locking/share_mode_lock.c:649(share_mode_data_store) Nov 21 22:26:45 Hippo smbd[5707]: share_mode_data_store: Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054521, 1, pid=5707, effective(1000, 100), real(1000, 0), class=rpc_parse] ../../source3/locking/share_mode_lock.c:650(share_mode_data_store) Nov 21 22:26:45 Hippo smbd[5707]: d: struct share_mode_data Nov 21 22:26:45 Hippo smbd[5707]: unique_content_epoch : 0x84985839a86ac261 (-8892260459581095327) Nov 21 22:26:45 Hippo smbd[5707]: flags : 0x01e0 (480) Nov 21 22:26:45 Hippo smbd[5707]: 1: SHARE_MODE_SHARE_DELETE Nov 21 22:26:45 Hippo smbd[5707]: 1: SHARE_MODE_SHARE_WRITE Nov 21 22:26:45 Hippo smbd[5707]: 1: SHARE_MODE_SHARE_READ Nov 21 22:26:45 Hippo smbd[5707]: 1: SHARE_MODE_ACCESS_DELETE Nov 21 22:26:45 Hippo smbd[5707]: 0: SHARE_MODE_ACCESS_WRITE Nov 21 22:26:45 Hippo smbd[5707]: 0: SHARE_MODE_ACCESS_READ Nov 21 22:26:45 Hippo smbd[5707]: 0: SHARE_MODE_LEASE_HANDLE Nov 21 22:26:45 Hippo smbd[5707]: 0: SHARE_MODE_LEASE_WRITE Nov 21 22:26:45 Hippo smbd[5707]: 0: SHARE_MODE_LEASE_READ Nov 21 22:26:45 Hippo smbd[5707]: servicepath : * Nov 21 22:26:45 Hippo smbd[5707]: servicepath : '/mnt/user/incoming' Nov 21 22:26:45 Hippo smbd[5707]: base_name : * Nov 21 22:26:45 Hippo smbd[5707]: base_name : 'temp2/temp.txt' Nov 21 22:26:45 Hippo smbd[5707]: stream_name : NULL Nov 21 22:26:45 Hippo smbd[5707]: num_delete_tokens : 0x00000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: delete_tokens: ARRAY(0) Nov 21 22:26:45 Hippo smbd[5707]: old_write_time : Mon Nov 21 22:25:37 2022 CST Nov 21 22:26:45 Hippo smbd[5707]: changed_write_time : NTTIME(0) Nov 21 22:26:45 Hippo smbd[5707]: fresh : 0x00 (0) Nov 21 22:26:45 Hippo smbd[5707]: modified : 0x01 (1) Nov 21 22:26:45 Hippo smbd[5707]: id: struct file_id Nov 21 22:26:45 Hippo smbd[5707]: devid : 0x0000000000000038 (56) Nov 21 22:26:45 Hippo smbd[5707]: inode : 0x0000000000006d37 (27959) Nov 21 22:26:45 Hippo smbd[5707]: extid : 0x0000000000000000 (0) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054742, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:487(dbwrap_watched_do_locked) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_do_locked: dbwrap_watched_do_locked_fn returned Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054762, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:550(dbwrap_watched_trigger_wakeup) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_trigger_wakeup: No one to wakeup Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054781, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:507(dbwrap_watched_record_prepare_wakeup) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_record_prepare_wakeup: No watchers Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054799, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:487(dbwrap_watched_do_locked) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_do_locked: dbwrap_watched_do_locked_fn returned Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054816, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:550(dbwrap_watched_trigger_wakeup) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_watched_trigger_wakeup: No one to wakeup Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054832, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap.c:175(dbwrap_lock_order_unlock) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_lock_order_unlock: release lock order 1 for /var/cache/samba/locking.tdb Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054850, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/notify_msg.c:220(notify_trigger) Nov 21 22:26:45 Hippo smbd[5707]: notify_trigger: notify_trigger called action=0x2, filter=0x1, dir=/mnt/user/incoming, name=temp2/temp.txt Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054898, 4, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/sec_ctx.c:206(push_sec_ctx) Nov 21 22:26:45 Hippo smbd[5707]: push_sec_ctx(1000, 100) : sec_ctx_stack_ndx = 1 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054920, 4, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/uid.c:566(push_conn_ctx) Nov 21 22:26:45 Hippo smbd[5707]: push_conn_ctx(642822937) : conn_ctx_stack_ndx = 0 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054938, 4, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal) Nov 21 22:26:45 Hippo smbd[5707]: setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054961, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../libcli/security/security_token.c:47(security_token_debug) Nov 21 22:26:45 Hippo smbd[5707]: Security token: (NULL) Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.054980, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/auth/token_util.c:873(debug_unix_user_token) Nov 21 22:26:45 Hippo smbd[5707]: UNIX token of user 0 Nov 21 22:26:45 Hippo smbd[5707]: Primary group is 0 and contains 0 supplementary groups Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.055032, 10, pid=5707, effective(0, 0), real(0, 0)] ../../lib/messaging/messages_dgm.c:1463(messaging_dgm_send) Nov 21 22:26:45 Hippo smbd[5707]: messaging_dgm_send: Sending message to 4175 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.055074, 4, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/sec_ctx.c:443(pop_sec_ctx) Nov 21 22:26:45 Hippo smbd[5707]: pop_sec_ctx (1000, 100) - sec_ctx_stack_ndx = 0 Nov 21 22:26:45 Hippo smbd[4175]: [2022/11/21 22:26:45.055093, 10, pid=4175, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:421(messaging_recv_cb) Nov 21 22:26:45 Hippo smbd[4175]: messaging_recv_cb: Received message 0x31b len 58 (num_fds:0) from 5707 Nov 21 22:26:45 Hippo smbd[4175]: [2022/11/21 22:26:45.055143, 10, pid=4175, effective(0, 0), real(0, 0)] ../../source3/smbd/notifyd/notifyd.c:647(notifyd_trigger) Nov 21 22:26:45 Hippo smbd[4175]: notifyd_trigger: Got trigger_msg action=2, filter=1, path=/mnt/user/incoming/temp2/temp.txt Nov 21 22:26:45 Hippo smbd[4175]: [2022/11/21 22:26:45.055174, 10, pid=4175, effective(0, 0), real(0, 0)] ../../source3/smbd/notifyd/notifyd.c:667(notifyd_trigger) Nov 21 22:26:45 Hippo smbd[4175]: notifyd_trigger: Trying path /mnt Nov 21 22:26:45 Hippo smbd[4175]: [2022/11/21 22:26:45.055203, 10, pid=4175, effective(0, 0), real(0, 0)] ../../source3/smbd/notifyd/notifyd.c:667(notifyd_trigger) Nov 21 22:26:45 Hippo smbd[4175]: notifyd_trigger: Trying path /mnt/user Nov 21 22:26:45 Hippo smbd[4175]: [2022/11/21 22:26:45.055231, 10, pid=4175, effective(0, 0), real(0, 0)] ../../source3/smbd/notifyd/notifyd.c:667(notifyd_trigger) Nov 21 22:26:45 Hippo smbd[4175]: notifyd_trigger: Trying path /mnt/user/incoming Nov 21 22:26:45 Hippo smbd[4175]: [2022/11/21 22:26:45.055260, 10, pid=4175, effective(0, 0), real(0, 0)] ../../source3/smbd/notifyd/notifyd.c:667(notifyd_trigger) Nov 21 22:26:45 Hippo smbd[4175]: notifyd_trigger: Trying path /mnt/user/incoming/temp2 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.055261, 2, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/close.c:830(close_normal_file) Nov 21 22:26:45 Hippo smbd[5707]: admin closed file temp2/temp.txt (numopen=0) NT_STATUS_ACCESS_DENIED Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.055306, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap.c:143(dbwrap_lock_order_lock) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_lock_order_lock: check lock order 1 for /var/cache/samba/smbXsrv_open_global.tdb Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.055335, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap.c:128(debug_lock_order) Nov 21 22:26:45 Hippo smbd[5707]: lock order: 1:/var/cache/samba/smbXsrv_open_global.tdb 2: 3: 4: Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.055364, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key) Nov 21 22:26:45 Hippo smbd[5707]: db_tdb_log_key: Locking key DCDA5258 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.055394, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap_tdb.c:153(db_tdb_fetch_locked_internal) Nov 21 22:26:45 Hippo smbd[5707]: db_tdb_fetch_locked_internal: Allocated locked data 0x55a398976230 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.055430, 10, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key) Nov 21 22:26:45 Hippo smbd[5707]: db_tdb_log_key: Unlocking key DCDA5258 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.055457, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../lib/dbwrap/dbwrap.c:175(dbwrap_lock_order_unlock) Nov 21 22:26:45 Hippo smbd[5707]: dbwrap_lock_order_unlock: release lock order 1 for /var/cache/samba/smbXsrv_open_global.tdb Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.055486, 5, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_close.c:226(smbd_smb2_close) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_close: close_file[]: NT_STATUS_ACCESS_DENIED Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.055518, 3, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_server.c:3955(smbd_smb2_request_error_ex) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] || at ../../source3/smbd/smb2_close.c:111 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.055548, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_server.c:3841(smbd_smb2_request_done_ex) Nov 21 22:26:45 Hippo smbd[5707]: smbd_smb2_request_done_ex: mid [108] idx[1] status[NT_STATUS_ACCESS_DENIED] body[8] dyn[yes:1] at ../../source3/smbd/smb2_server.c:4005 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.055578, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2_credits] ../../source3/smbd/smb2_server.c:969(smb2_set_operation_credit) Nov 21 22:26:45 Hippo smbd[5707]: smb2_set_operation_credit: smb2_set_operation_credit: requested 2, charge 1, granted 2, current possible/max 7487/8192, total granted/max/low/range 707/8192/109/707 Nov 21 22:26:45 Hippo smbd[5707]: [2022/11/21 22:26:45.055611, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../libcli/smb/smb2_signing.c:657(smb2_signing_sign_pdu) Nov 21 22:26:45 Hippo smbd[5707]: signed SMB2 message (sign_algo_id=0) Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942026, 10, pid=5707, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_server.c:1681(smbd_server_connection_terminate_ex) Nov 21 22:26:51 Hippo smbd[5707]: smbd_server_connection_terminate_ex: conn[ptr=0x55a3989333c0,id=0,addr=ipv4:192.168.1.139:54864] num_ok[0] reason[NT_STATUS_END_OF_FILE] at ../../source3/smbd/smb2_server.c:5048 Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942101, 4, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal) Nov 21 22:26:51 Hippo smbd[5707]: setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942123, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../libcli/security/security_token.c:47(security_token_debug) Nov 21 22:26:51 Hippo smbd[5707]: Security token: (NULL) Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942148, 5, pid=5707, effective(1000, 100), real(1000, 0)] ../../source3/auth/token_util.c:873(debug_unix_user_token) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942128, 10, pid=5706, effective(1000, 100), real(1000, 0), class=smb2] ../../source3/smbd/smb2_server.c:1681(smbd_server_connection_terminate_ex) Nov 21 22:26:51 Hippo smbd[5707]: UNIX token of user 0 Nov 21 22:26:51 Hippo smbd[5706]: smbd_server_connection_terminate_ex: conn[ptr=0x55a3989333c0,id=0,addr=ipv4:192.168.1.139:54862] num_ok[0] reason[NT_STATUS_END_OF_FILE] at ../../source3/smbd/smb2_server.c:5048 Nov 21 22:26:51 Hippo smbd[5707]: Primary group is 0 and contains 0 supplementary groups Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942170, 4, pid=5706, effective(1000, 100), real(1000, 0)] ../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal) Nov 21 22:26:51 Hippo smbd[5706]: setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942190, 5, pid=5707, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:494(smbd_change_to_root_user) Nov 21 22:26:51 Hippo smbd[5707]: change_to_root_user: now uid=(0,0) gid=(0,0) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942218, 5, pid=5706, effective(1000, 100), real(1000, 0)] ../../libcli/security/security_token.c:47(security_token_debug) Nov 21 22:26:51 Hippo smbd[5706]: Security token: (NULL) Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942238, 4, pid=5707, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal) Nov 21 22:26:51 Hippo smbd[5707]: setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942248, 5, pid=5706, effective(1000, 100), real(1000, 0)] ../../source3/auth/token_util.c:873(debug_unix_user_token) Nov 21 22:26:51 Hippo smbd[5706]: UNIX token of user 0 Nov 21 22:26:51 Hippo smbd[5706]: Primary group is 0 and contains 0 supplementary groups Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942265, 5, pid=5707, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug) Nov 21 22:26:51 Hippo smbd[5707]: Security token: (NULL) Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942288, 5, pid=5707, effective(0, 0), real(0, 0)] ../../source3/auth/token_util.c:873(debug_unix_user_token) Nov 21 22:26:51 Hippo smbd[5707]: UNIX token of user 0 Nov 21 22:26:51 Hippo smbd[5707]: Primary group is 0 and contains 0 supplementary groups Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942291, 5, pid=5706, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:494(smbd_change_to_root_user) Nov 21 22:26:51 Hippo smbd[5706]: change_to_root_user: now uid=(0,0) gid=(0,0) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942316, 4, pid=5706, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal) Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942316, 5, pid=5707, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:494(smbd_change_to_root_user) Nov 21 22:26:51 Hippo smbd[5706]: setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 Nov 21 22:26:51 Hippo smbd[5707]: change_to_root_user: now uid=(0,0) gid=(0,0) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942332, 5, pid=5706, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug) Nov 21 22:26:51 Hippo smbd[5706]: Security token: (NULL) Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942335, 4, pid=5707, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal) Nov 21 22:26:51 Hippo smbd[5707]: setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942347, 5, pid=5706, effective(0, 0), real(0, 0)] ../../source3/auth/token_util.c:873(debug_unix_user_token) Nov 21 22:26:51 Hippo smbd[5706]: UNIX token of user 0 Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942351, 5, pid=5707, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug) Nov 21 22:26:51 Hippo smbd[5707]: Security token: (NULL) Nov 21 22:26:51 Hippo smbd[5706]: Primary group is 0 and contains 0 supplementary groups Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942366, 5, pid=5707, effective(0, 0), real(0, 0)] ../../source3/auth/token_util.c:873(debug_unix_user_token) Nov 21 22:26:51 Hippo smbd[5707]: UNIX token of user 0 Nov 21 22:26:51 Hippo smbd[5707]: Primary group is 0 and contains 0 supplementary groups Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942373, 5, pid=5706, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:494(smbd_change_to_root_user) Nov 21 22:26:51 Hippo smbd[5706]: change_to_root_user: now uid=(0,0) gid=(0,0) Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942390, 5, pid=5707, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:494(smbd_change_to_root_user) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942393, 4, pid=5706, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal) Nov 21 22:26:51 Hippo smbd[5707]: change_to_root_user: now uid=(0,0) gid=(0,0) Nov 21 22:26:51 Hippo smbd[5706]: setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942409, 5, pid=5706, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug) Nov 21 22:26:51 Hippo smbd[5706]: Security token: (NULL) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942432, 5, pid=5706, effective(0, 0), real(0, 0)] ../../source3/auth/token_util.c:873(debug_unix_user_token) Nov 21 22:26:51 Hippo smbd[5706]: UNIX token of user 0 Nov 21 22:26:51 Hippo smbd[5706]: Primary group is 0 and contains 0 supplementary groups Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942442, 10, pid=5707, effective(0, 0), real(0, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:487(dbwrap_watched_do_locked) Nov 21 22:26:51 Hippo smbd[5707]: dbwrap_watched_do_locked: dbwrap_watched_do_locked_fn returned Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942460, 10, pid=5707, effective(0, 0), real(0, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:550(dbwrap_watched_trigger_wakeup) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942459, 5, pid=5706, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:494(smbd_change_to_root_user) Nov 21 22:26:51 Hippo smbd[5707]: dbwrap_watched_trigger_wakeup: No one to wakeup Nov 21 22:26:51 Hippo smbd[5706]: change_to_root_user: now uid=(0,0) gid=(0,0) Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942476, 10, pid=5707, effective(0, 0), real(0, 0)] ../../source3/lib/g_lock.c:916(g_lock_lock) Nov 21 22:26:51 Hippo smbd[5707]: g_lock_lock: status=NT_STATUS_OK, state.status=NT_STATUS_OK Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942484, 4, pid=5706, effective(0, 0), real(0, 0), class=vfs] ../../source3/smbd/vfs.c:938(vfs_ChDir) Nov 21 22:26:51 Hippo smbd[5706]: vfs_ChDir to /mnt/user/incoming Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942493, 5, pid=5707, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:143(dbwrap_lock_order_lock) Nov 21 22:26:51 Hippo smbd[5707]: dbwrap_lock_order_lock: check lock order 1 for /var/cache/samba/locking.tdb Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942510, 10, pid=5707, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:128(debug_lock_order) Nov 21 22:26:51 Hippo smbd[5707]: lock order: 1:/var/cache/samba/locking.tdb 2: 3: 4: Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942532, 10, pid=5707, effective(0, 0), real(0, 0), class=locking] ../../source3/locking/share_mode_lock.c:849(get_static_share_mode_data) Nov 21 22:26:51 Hippo smbd[5707]: get_static_share_mode_data: get_static_share_mode_data_fn failed: NT_STATUS_NOT_FOUND Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942554, 10, pid=5707, effective(0, 0), real(0, 0), class=locking] ../../source3/locking/share_mode_lock.c:929(get_share_mode_lock) Nov 21 22:26:51 Hippo smbd[5707]: get_share_mode_lock: get_static_share_mode_data failed: NT_STATUS_NOT_FOUND Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942577, 10, pid=5707, effective(0, 0), real(0, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:507(dbwrap_watched_record_prepare_wakeup) Nov 21 22:26:51 Hippo smbd[5707]: dbwrap_watched_record_prepare_wakeup: No watchers Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942597, 10, pid=5707, effective(0, 0), real(0, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:487(dbwrap_watched_do_locked) Nov 21 22:26:51 Hippo smbd[5707]: dbwrap_watched_do_locked: dbwrap_watched_do_locked_fn returned Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942613, 10, pid=5707, effective(0, 0), real(0, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:550(dbwrap_watched_trigger_wakeup) Nov 21 22:26:51 Hippo smbd[5707]: dbwrap_watched_trigger_wakeup: No one to wakeup Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942640, 5, pid=5707, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:175(dbwrap_lock_order_unlock) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942637, 10, pid=5706, effective(0, 0), real(0, 0)] ../../source3/modules/vfs_widelinks.c:215(widelinks_chdir) Nov 21 22:26:51 Hippo smbd[5707]: dbwrap_lock_order_unlock: release lock order 1 for /var/cache/samba/locking.tdb Nov 21 22:26:51 Hippo smbd[5706]: widelinks_chdir: config->cwd now |/mnt/user/incoming| Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942669, 0, pid=5707, effective(0, 0), real(0, 0)] ../../source3/smbd/close.c:312(close_remove_share_mode) Nov 21 22:26:51 Hippo smbd[5707]: close_remove_share_mode: Could not get share mode lock for file temp2/temp.txt Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942690, 5, pid=5706, effective(0, 0), real(0, 0), class=vfs] ../../source3/smbd/vfs.c:1000(vfs_ChDir) Nov 21 22:26:51 Hippo smbd[5706]: vfs_ChDir: vfs_ChDir got /mnt/user/incoming Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942710, 4, pid=5706, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal) Nov 21 22:26:51 Hippo smbd[5706]: setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942726, 5, pid=5706, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug) Nov 21 22:26:51 Hippo smbd[5706]: Security token: (NULL) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942741, 5, pid=5706, effective(0, 0), real(0, 0)] ../../source3/auth/token_util.c:873(debug_unix_user_token) Nov 21 22:26:51 Hippo smbd[5706]: UNIX token of user 0 Nov 21 22:26:51 Hippo smbd[5706]: Primary group is 0 and contains 0 supplementary groups Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942768, 5, pid=5706, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:494(smbd_change_to_root_user) Nov 21 22:26:51 Hippo smbd[5706]: change_to_root_user: now uid=(0,0) gid=(0,0) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942793, 2, pid=5706, effective(0, 0), real(0, 0)] ../../source3/smbd/smb2_service.c:948(close_cnum) Nov 21 22:26:51 Hippo smbd[5706]: (ipv4:192.168.1.139:54862) closed connection to service incoming Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942815, 4, pid=5706, effective(0, 0), real(0, 0), class=vfs] ../../source3/smbd/vfs.c:938(vfs_ChDir) Nov 21 22:26:51 Hippo smbd[5706]: vfs_ChDir to / Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942834, 10, pid=5706, effective(0, 0), real(0, 0)] ../../source3/modules/vfs_widelinks.c:215(widelinks_chdir) Nov 21 22:26:51 Hippo smbd[5706]: widelinks_chdir: config->cwd now |/| Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942857, 5, pid=5706, effective(0, 0), real(0, 0), class=vfs] ../../source3/smbd/vfs.c:1000(vfs_ChDir) Nov 21 22:26:51 Hippo smbd[5706]: vfs_ChDir: vfs_ChDir got / Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942883, 4, pid=5706, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal) Nov 21 22:26:51 Hippo smbd[5706]: setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942900, 5, pid=5706, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug) Nov 21 22:26:51 Hippo smbd[5706]: Security token: (NULL) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942916, 5, pid=5706, effective(0, 0), real(0, 0)] ../../source3/auth/token_util.c:873(debug_unix_user_token) Nov 21 22:26:51 Hippo smbd[5706]: UNIX token of user 0 Nov 21 22:26:51 Hippo smbd[5706]: Primary group is 0 and contains 0 supplementary groups Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942913, 2, pid=5707, effective(0, 0), real(0, 0)] ../../source3/smbd/close.c:830(close_normal_file) Nov 21 22:26:51 Hippo smbd[5707]: admin closed file temp2/temp.txt (numopen=0) NT_STATUS_INVALID_PARAMETER Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942943, 5, pid=5706, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:494(smbd_change_to_root_user) Nov 21 22:26:51 Hippo smbd[5706]: change_to_root_user: now uid=(0,0) gid=(0,0) Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942948, 0, pid=5707, effective(0, 0), real(0, 0)] ../../source3/smbd/fd_handle.c:39(fd_handle_destructor) Nov 21 22:26:51 Hippo smbd[5707]: PANIC: assert failed at ../../source3/smbd/fd_handle.c(39): (fh->fd == -1) || (fh->fd == AT_FDCWD) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942971, 5, pid=5706, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:143(dbwrap_lock_order_lock) Nov 21 22:26:51 Hippo smbd[5706]: dbwrap_lock_order_lock: check lock order 1 for /var/cache/samba/smbXsrv_tcon_global.tdb Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942973, 0, pid=5707, effective(0, 0), real(0, 0)] ../../lib/util/fault.c:173(smb_panic_log) Nov 21 22:26:51 Hippo smbd[5707]: =============================================================== Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.942989, 10, pid=5706, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:128(debug_lock_order) Nov 21 22:26:51 Hippo smbd[5706]: lock order: 1:/var/cache/samba/smbXsrv_tcon_global.tdb 2: 3: 4: Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.942998, 0, pid=5707, effective(0, 0), real(0, 0)] ../../lib/util/fault.c:174(smb_panic_log) Nov 21 22:26:51 Hippo smbd[5707]: INTERNAL ERROR: assert failed: (fh->fd == -1) || (fh->fd == AT_FDCWD) in pid 5707 (4.17.2) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943007, 10, pid=5706, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key) Nov 21 22:26:51 Hippo smbd[5706]: db_tdb_log_key: Locking key 839C2778 Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.943015, 0, pid=5707, effective(0, 0), real(0, 0)] ../../lib/util/fault.c:178(smb_panic_log) Nov 21 22:26:51 Hippo smbd[5707]: If you are running a recent Samba version, and if you think this problem is not yet fixed in the latest versions, please consider reporting this bug, see https://wiki.samba.org/index.php/Bug_Reporting Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943026, 10, pid=5706, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:153(db_tdb_fetch_locked_internal) Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.943031, 0, pid=5707, effective(0, 0), real(0, 0)] ../../lib/util/fault.c:183(smb_panic_log) Nov 21 22:26:51 Hippo smbd[5706]: db_tdb_fetch_locked_internal: Allocated locked data 0x55a398942fa0 Nov 21 22:26:51 Hippo smbd[5707]: =============================================================== Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.943046, 0, pid=5707, effective(0, 0), real(0, 0)] ../../lib/util/fault.c:184(smb_panic_log) Nov 21 22:26:51 Hippo smbd[5707]: PANIC (pid 5707): assert failed: (fh->fd == -1) || (fh->fd == AT_FDCWD) in 4.17.2 Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943055, 10, pid=5706, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key) Nov 21 22:26:51 Hippo smbd[5706]: db_tdb_log_key: Unlocking key 839C2778 Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943072, 5, pid=5706, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:175(dbwrap_lock_order_unlock) Nov 21 22:26:51 Hippo smbd[5706]: dbwrap_lock_order_unlock: release lock order 1 for /var/cache/samba/smbXsrv_tcon_global.tdb Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943094, 5, pid=5706, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:143(dbwrap_lock_order_lock) Nov 21 22:26:51 Hippo smbd[5706]: dbwrap_lock_order_lock: check lock order 1 for /var/cache/samba/smbXsrv_session_global.tdb Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943116, 10, pid=5706, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:128(debug_lock_order) Nov 21 22:26:51 Hippo smbd[5706]: lock order: 1:/var/cache/samba/smbXsrv_session_global.tdb 2: 3: 4: Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943136, 10, pid=5706, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key) Nov 21 22:26:51 Hippo smbd[5706]: db_tdb_log_key: Locking key F66AD310 Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943159, 10, pid=5706, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:153(db_tdb_fetch_locked_internal) Nov 21 22:26:51 Hippo smbd[5706]: db_tdb_fetch_locked_internal: Allocated locked data 0x55a39898bb10 Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943178, 10, pid=5706, effective(0, 0), real(0, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:507(dbwrap_watched_record_prepare_wakeup) Nov 21 22:26:51 Hippo smbd[5706]: dbwrap_watched_record_prepare_wakeup: No watchers Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943200, 5, pid=5706, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:175(dbwrap_lock_order_unlock) Nov 21 22:26:51 Hippo smbd[5706]: dbwrap_lock_order_unlock: release lock order 1 for /var/cache/samba/smbXsrv_session_global.tdb Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943219, 10, pid=5706, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key) Nov 21 22:26:51 Hippo smbd[5706]: db_tdb_log_key: Unlocking key F66AD310 Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943236, 10, pid=5706, effective(0, 0), real(0, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:550(dbwrap_watched_trigger_wakeup) Nov 21 22:26:51 Hippo smbd[5706]: dbwrap_watched_trigger_wakeup: No one to wakeup Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943254, 4, pid=5706, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal) Nov 21 22:26:51 Hippo smbd[5706]: setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943270, 5, pid=5706, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug) Nov 21 22:26:51 Hippo smbd[5706]: Security token: (NULL) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943287, 5, pid=5706, effective(0, 0), real(0, 0)] ../../source3/auth/token_util.c:873(debug_unix_user_token) Nov 21 22:26:51 Hippo smbd[5706]: UNIX token of user 0 Nov 21 22:26:51 Hippo smbd[5706]: Primary group is 0 and contains 0 supplementary groups Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943314, 5, pid=5706, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:494(smbd_change_to_root_user) Nov 21 22:26:51 Hippo smbd[5706]: change_to_root_user: now uid=(0,0) gid=(0,0) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943333, 10, pid=5706, effective(0, 0), real(0, 0)] ../../source3/smbd/smb2_process.c:1196(smbXsrv_connection_destructor) Nov 21 22:26:51 Hippo smbd[5706]: smbXsrv_connection_destructor: xconn[ptr=0x55a3989333c0,id=0,addr=ipv4:192.168.1.139:54862] Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943356, 4, pid=5706, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal) Nov 21 22:26:51 Hippo smbd[5706]: setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943373, 5, pid=5706, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug) Nov 21 22:26:51 Hippo smbd[5706]: Security token: (NULL) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943389, 5, pid=5706, effective(0, 0), real(0, 0)] ../../source3/auth/token_util.c:873(debug_unix_user_token) Nov 21 22:26:51 Hippo smbd[5706]: UNIX token of user 0 Nov 21 22:26:51 Hippo smbd[5706]: Primary group is 0 and contains 0 supplementary groups Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943416, 5, pid=5706, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:494(smbd_change_to_root_user) Nov 21 22:26:51 Hippo smbd[5706]: change_to_root_user: now uid=(0,0) gid=(0,0) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943435, 5, pid=5706, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:764(messaging_deregister) Nov 21 22:26:51 Hippo smbd[5706]: Deregistering messaging pointer for type 784 - private_data=0x55a39893fa60 Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943472, 10, pid=5706, effective(0, 0), real(0, 0)] ../../source3/smbd/smbXsrv_session.c:1271(smbXsrv_session_destructor) Nov 21 22:26:51 Hippo smbd[5706]: smbXsrv_session_destructor: destructing session(4134195984) Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943513, 10, pid=5706, effective(0, 0), real(0, 0)] ../../lib/messaging/messages_dgm_ref.c:163(msg_dgm_ref_destructor) Nov 21 22:26:51 Hippo smbd[5706]: msg_dgm_ref_destructor: refs=(nil) Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.943580, 0, pid=5707, effective(0, 0), real(0, 0)] ../../lib/util/fault.c:292(log_stack_trace) Nov 21 22:26:51 Hippo smbd[5707]: BACKTRACE: 38 stack frames: Nov 21 22:26:51 Hippo smbd[5707]: #0 /usr/lib64/libgenrand-samba4.so(log_stack_trace+0x2e) [0x147c139fc64e] Nov 21 22:26:51 Hippo smbd[5707]: #1 /usr/lib64/libgenrand-samba4.so(smb_panic+0x9) [0x147c139fc8a9] Nov 21 22:26:51 Hippo smbd[5707]: #2 /usr/lib64/libsmbd-base-samba4.so(+0x4d10b) [0x147c13ddd10b] Nov 21 22:26:51 Hippo smbd[5707]: #3 /usr/lib64/libtalloc.so.2(+0x44df) [0x147c139ac4df] Nov 21 22:26:51 Hippo smbd[5707]: #4 /usr/lib64/libsmbd-base-samba4.so(file_free+0xd6) [0x147c13dea2e6] Nov 21 22:26:51 Hippo smbd[5707]: #5 /usr/lib64/libsmbd-base-samba4.so(close_file_free+0x29) [0x147c13e1ad49] Nov 21 22:26:51 Hippo smbd[5707]: #6 /usr/lib64/libsmbd-base-samba4.so(+0x5d046) [0x147c13ded046] Nov 21 22:26:51 Hippo smbd[5707]: #7 /usr/lib64/libsmbd-base-samba4.so(+0x5d1ce) [0x147c13ded1ce] Nov 21 22:26:51 Hippo smbd[5707]: #8 /usr/lib64/libsmbd-base-samba4.so(files_forall+0x19) [0x147c13de9119] Nov 21 22:26:51 Hippo smbd[5707]: #9 /usr/lib64/libsmbd-base-samba4.so(file_close_user+0x3d) [0x147c13de925d] Nov 21 22:26:51 Hippo smbd[5707]: #10 /usr/lib64/libsmbd-base-samba4.so(smbXsrv_session_logoff+0x4d) [0x147c13e6600d] Nov 21 22:26:51 Hippo smbd[5707]: #11 /usr/lib64/libsmbd-base-samba4.so(+0xd6445) [0x147c13e66445] Nov 21 22:26:51 Hippo smbd[5706]: [2022/11/21 22:26:51.943671, 3, pid=5706, effective(0, 0), real(0, 0)] ../../source3/smbd/server_exit.c:229(exit_server_common) Nov 21 22:26:51 Hippo smbd[5706]: Server exit (NT_STATUS_END_OF_FILE) Nov 21 22:26:51 Hippo smbd[5707]: #12 /usr/lib64/libdbwrap-samba4.so(+0x5d18) [0x147c136dfd18] Nov 21 22:26:51 Hippo smbd[5707]: #13 /usr/lib64/libdbwrap-samba4.so(+0x5f3a) [0x147c136dff3a] Nov 21 22:26:51 Hippo smbd[5707]: #14 /usr/lib64/libdbwrap-samba4.so(dbwrap_traverse+0x7) [0x147c136ddf67] Nov 21 22:26:51 Hippo smbd[5707]: #15 /usr/lib64/libsmbd-base-samba4.so(smbXsrv_session_logoff_all+0x5b) [0x147c13e667fb] Nov 21 22:26:51 Hippo smbd[5707]: #16 /usr/lib64/libsmbd-base-samba4.so(+0xdca0b) [0x147c13e6ca0b] Nov 21 22:26:51 Hippo smbd[5707]: #17 /usr/lib64/libsmbd-base-samba4.so(smbd_reinit_after_fork+0) [0x147c13e6cff0] Nov 21 22:26:51 Hippo smbd[5707]: #18 /usr/lib64/libsmbd-shim-samba4.so(exit_server_cleanly+0x14) [0x147c13a01284] Nov 21 22:26:51 Hippo smbd[5707]: #19 /usr/lib64/libsmbd-base-samba4.so(smbd_server_connection_terminate_ex+0x4d6) [0x147c13e424c6] Nov 21 22:26:51 Hippo smbd[5707]: #20 /usr/lib64/libtevent.so.0(tevent_common_invoke_fd_handler+0x91) [0x147c139bf791] Nov 21 22:26:51 Hippo smbd[5707]: #21 /usr/lib64/libtevent.so.0(+0xec87) [0x147c139c5c87] Nov 21 22:26:51 Hippo smbd[5707]: #22 /usr/lib64/libtevent.so.0(+0xcd77) [0x147c139c3d77] Nov 21 22:26:51 Hippo smbd[5707]: #23 /usr/lib64/libtevent.so.0(_tevent_loop_once+0x91) [0x147c139beb61] Nov 21 22:26:51 Hippo smbd[5707]: #24 /usr/lib64/libtevent.so.0(tevent_common_loop_wait+0x1b) [0x147c139bee3b] Nov 21 22:26:51 Hippo smbd[5707]: #25 /usr/lib64/libtevent.so.0(+0xcd17) [0x147c139c3d17] Nov 21 22:26:51 Hippo smbd[5707]: #26 /usr/lib64/libsmbd-base-samba4.so(smbd_process+0x817) [0x147c13e33ce7] Nov 21 22:26:51 Hippo smbd[5707]: #27 /usr/sbin/smbd(+0xb090) [0x55a3988cb090] Nov 21 22:26:51 Hippo smbd[5707]: #28 /usr/lib64/libtevent.so.0(tevent_common_invoke_fd_handler+0x91) [0x147c139bf791] Nov 21 22:26:51 Hippo smbd[5707]: #29 /usr/lib64/libtevent.so.0(+0xec87) [0x147c139c5c87] Nov 21 22:26:51 Hippo smbd[5707]: #30 /usr/lib64/libtevent.so.0(+0xcd77) [0x147c139c3d77] Nov 21 22:26:51 Hippo smbd[5707]: #31 /usr/lib64/libtevent.so.0(_tevent_loop_once+0x91) [0x147c139beb61] Nov 21 22:26:51 Hippo smbd[5707]: #32 /usr/lib64/libtevent.so.0(tevent_common_loop_wait+0x1b) [0x147c139bee3b] Nov 21 22:26:51 Hippo smbd[5707]: #33 /usr/lib64/libtevent.so.0(+0xcd17) [0x147c139c3d17] Nov 21 22:26:51 Hippo smbd[5707]: #34 /usr/sbin/smbd(main+0x1489) [0x55a3988c8259] Nov 21 22:26:51 Hippo smbd[5707]: #35 /lib64/libc.so.6(+0x23177) [0x147c137ca177] Nov 21 22:26:51 Hippo smbd[5707]: #36 /lib64/libc.so.6(__libc_start_main+0x85) [0x147c137ca235] Nov 21 22:26:51 Hippo smbd[5707]: #37 /usr/sbin/smbd(_start+0x21) [0x55a3988c8b31] Nov 21 22:26:51 Hippo smbd[5707]: [2022/11/21 22:26:51.943851, 0, pid=5707, effective(0, 0), real(0, 0)] ../../source3/lib/dumpcore.c:315(dump_core) Nov 21 22:26:51 Hippo smbd[5707]: dumping core in /var/log/samba/cores/smbd Nov 21 22:26:51 Hippo smbd[5707]: