Attachment 17668 Details for Bug 15247 – Level 10 samba server log

Level 10 samba server log

smbd level 10 log.txt (text/plain), 130.22 KB, created by RC Bosworth on 2022-11-22 04:57:45 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: RC Bosworth

Created: 2022-11-22 04:57:45 UTC

Size: 130.22 KB

patch

obsolete

>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:<none> 3:<none> 4:<none>
>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:<none> 3:<none> 4:<none>
>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:<none> 3:<none> 4:<none>
>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:<none> 3:<none> 4:<none>
>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:<none> 3:<none> 4:<none>
>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:<none> 3:<none> 4:<none>
>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:<none> 3:<none> 4:<none>
>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:<none> 3:<none> 4:<none>
>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]:

Actions: View

Attachments on bug 15247: 17667 | 17668