[1970/01/02 09:51:59.330442, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/smb2_create.c:456(smbd_smb2_create_send) smbd_smb2_create: name[snmp.txt] [1970/01/02 09:51:59.330634, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/smb2_create.c:768(smbd_smb2_create_send) smbd_smb2_create_send: open execution phase [1970/01/02 09:51:59.330813, 5, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/filename.c:258(unix_convert) unix_convert called on file "snmp.txt" [1970/01/02 09:51:59.331007, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/statcache.c:244(stat_cache_lookup) stat_cache_lookup: lookup failed for name [SNMP.TXT] [1970/01/02 09:51:59.331192, 5, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/filename.c:421(unix_convert) unix_convert begin: name = snmp.txt, dirpath = , start = snmp.txt [1970/01/02 09:51:59.331428, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/mangle_hash2.c:418(is_mangled) is_mangled snmp.txt ? [1970/01/02 09:51:59.331611, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/mangle_hash2.c:357(is_mangled_component) is_mangled_component snmp.txt (len 8) ? [1970/01/02 09:51:59.331809, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/mangle_hash2.c:418(is_mangled) is_mangled snmp.txt ? [1970/01/02 09:51:59.331973, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/mangle_hash2.c:357(is_mangled_component) is_mangled_component snmp.txt (len 8) ? [1970/01/02 09:51:59.332231, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/mangle_hash2.c:418(is_mangled) is_mangled snmp.txt ? [1970/01/02 09:51:59.332421, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/mangle_hash2.c:357(is_mangled_component) is_mangled_component snmp.txt (len 8) ? [1970/01/02 09:51:59.332605, 5, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/filename.c:816(unix_convert) New file snmp.txt [1970/01/02 09:51:59.332780, 3, pid=18844, effective(9999, 9999), real(0, 0), class=vfs] ../source3/smbd/vfs.c:1137(check_reduced_name) check_reduced_name [snmp.txt] [/share/testDir] [1970/01/02 09:51:59.332995, 10, pid=18844, effective(9999, 9999), real(0, 0), class=vfs] ../source3/smbd/vfs.c:1197(check_reduced_name) check_reduced_name realpath [snmp.txt] -> [/share/testDir/snmp.txt] [1970/01/02 09:51:59.333199, 3, pid=18844, effective(9999, 9999), real(0, 0), class=vfs] ../source3/smbd/vfs.c:1267(check_reduced_name) check_reduced_name: snmp.txt reduced to /share/testDir/snmp.txt [1970/01/02 09:51:59.333391, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/open.c:4270(create_file_default) create_file: access_mask = 0x16019f file_attributes = 0x20, share_access = 0x0, create_disposition = 0x2 create_options = 0x44 oplock_request = 0x2 private_flags = 0x0 root_dir_fid = 0x0, ea_list = 0x0, sd = 0x0, fname = snmp.txt [1970/01/02 09:51:59.333746, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/open.c:3778(create_file_unixpath) create_file_unixpath: access_mask = 0x16019f file_attributes = 0x20, share_access = 0x0, create_disposition = 0x2 create_options = 0x44 oplock_request = 0x2 private_flags = 0x0 ea_list = 0x0, sd = 0x0, fname = snmp.txt [1970/01/02 09:51:59.334116, 5, pid=18844, effective(9999, 9999), real(0, 0)] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) check lock order 1 for /data/samba/lock/smbXsrv_open_global.tdb [1970/01/02 09:51:59.334322, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) lock order: 1:/data/samba/lock/smbXsrv_open_global.tdb 2: 3: [1970/01/02 09:51:59.334618, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) Locking key F6E7667C [1970/01/02 09:51:59.334830, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal) Allocated locked data 0x41b69710 [1970/01/02 09:51:59.335141, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/smbXsrv_open.c:695(smbXsrv_open_global_store) [1970/01/02 09:51:59.335294, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/smbXsrv_open.c:697(smbXsrv_open_global_store) smbXsrv_open_global_store: key 'F6E7667C' stored [1970/01/02 09:51:59.335492, 1, pid=18844, effective(9999, 9999), real(0, 0)] ../librpc/ndr/ndr.c:296(ndr_print_debug) &global_blob: struct smbXsrv_open_globalB version : SMBXSRV_VERSION_0 (0) seqnum : 0x00000001 (1) info : union smbXsrv_open_globalU(case 0) info0 : * info0: struct smbXsrv_open_global0 db_rec : * server_id: struct server_id pid : 0x000000000000499c (18844) task_id : 0x00000000 (0) vnn : 0xffffffff (4294967295) unique_id : 0xd0d52b75d43d6240 (-3398762558709210560) open_global_id : 0xf6e7667c (4142360188) open_persistent_id : 0x00000000f6e7667c (4142360188) open_volatile_id : 0x00000000b3669801 (3009845249) open_owner : S-1-5-21-1437706870-893245598-859329015-501 open_time : Fri Jan 2 09:51:59 1970 PST create_guid : 00000000-0000-0000-0000-000000000000 client_guid : 4d6ffccd-32fb-11e5-908f-00155d230dd3 app_instance_id : 00000000-0000-0000-0000-000000000000 disconnect_time : NTTIME(0) durable_timeout_msec : 0x00000000 (0) durable : 0x00 (0) backend_cookie : DATA_BLOB length=0 [1970/01/02 09:51:59.337844, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) Unlocking key F6E7667C [1970/01/02 09:51:59.338038, 5, pid=18844, effective(9999, 9999), real(0, 0)] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 1 for /data/samba/lock/smbXsrv_open_global.tdb [1970/01/02 09:51:59.338233, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) lock order: 1: 2: 3: [1970/01/02 09:51:59.338443, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/smbXsrv_open.c:862(smbXsrv_open_create) [1970/01/02 09:51:59.338576, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/smbXsrv_open.c:870(smbXsrv_open_create) smbXsrv_open_create: global_id (0xf6e7667c) stored [1970/01/02 09:51:59.338754, 1, pid=18844, effective(9999, 9999), real(0, 0)] ../librpc/ndr/ndr.c:296(ndr_print_debug) &open_blob: struct smbXsrv_openB version : SMBXSRV_VERSION_0 (0) reserved : 0x00000000 (0) info : union smbXsrv_openU(case 0) info0 : * info0: struct smbXsrv_open table : * db_rec : NULL local_id : 0xb3669801 (3009845249) global : * global: struct smbXsrv_open_global0 db_rec : NULL server_id: struct server_id pid : 0x000000000000499c (18844) task_id : 0x00000000 (0) vnn : 0xffffffff (4294967295) unique_id : 0xd0d52b75d43d6240 (-3398762558709210560) open_global_id : 0xf6e7667c (4142360188) open_persistent_id : 0x00000000f6e7667c (4142360188) open_volatile_id : 0x00000000b3669801 (3009845249) open_owner : S-1-5-21-1437706870-893245598-859329015-501 open_time : Fri Jan 2 09:51:59 1970 PST create_guid : 00000000-0000-0000-0000-000000000000 client_guid : 4d6ffccd-32fb-11e5-908f-00155d230dd3 app_instance_id : 00000000-0000-0000-0000-000000000000 disconnect_time : NTTIME(0) durable_timeout_msec : 0x00000000 (0) durable : 0x00 (0) backend_cookie : DATA_BLOB length=0 status : NT_STATUS_OK idle_time : Fri Jan 2 09:51:59 1970 PST compat : NULL [1970/01/02 09:51:59.341689, 5, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/files.c:128(file_new) allocated file structure fnum 3009845249 (2 used) [1970/01/02 09:51:59.341933, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/files.c:716(file_name_hash) file_name_hash: /share/testDir/snmp.txt hash 0xf95cacd5 [1970/01/02 09:51:59.342161, 3, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/dosmode.c:163(unix_mode) unix_mode(snmp.txt) returning 0744 [1970/01/02 09:51:59.342355, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/open.c:2129(open_file_ntcreate) open_file_ntcreate: fname=snmp.txt, dos_attrs=0x20 access_mask=0x16019f share_access=0x0 create_disposition = 0x2 create_options=0x44 unix mode=0744 oplock_request=2 private_flags = 0x0 [1970/01/02 09:51:59.342640, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/open.c:2287(open_file_ntcreate) open_file_ntcreate: fname=snmp.txt, after mapping access_mask=0x16019f [1970/01/02 09:51:59.342836, 4, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/open.c:2380(open_file_ntcreate) calling open_file with flags=0x2 flags2=0xC0 mode=0744, access_mask = 0x16019f, open_access_mask = 0x16019f [1970/01/02 09:51:59.343077, 10, pid=18844, effective(9999, 9999), real(0, 0), class=acls] ../source3/smbd/posix_acls.c:3529(posix_get_nt_acl) posix_get_nt_acl: called for file . [1970/01/02 09:51:59.343357, 5, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/passdb/lookup_sid.c:1163(uid_to_sid) uid_to_sid: winbind failed to find a sid for uid 0 [1970/01/02 09:51:59.343549, 4, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/sec_ctx.c:216(push_sec_ctx) push_sec_ctx(9999, 9999) : sec_ctx_stack_ndx = 1 [1970/01/02 09:51:59.343755, 4, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/uid.c:485(push_conn_ctx) push_conn_ctx(2278915804) : conn_ctx_stack_ndx = 0 [1970/01/02 09:51:59.343950, 4, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [1970/01/02 09:51:59.344133, 5, pid=18844, effective(9999, 9999), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [1970/01/02 09:51:59.344296, 5, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/auth/token_util.c:629(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [1970/01/02 09:51:59.344746, 4, pid=18844, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:216(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [1970/01/02 09:51:59.344960, 4, pid=18844, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:485(push_conn_ctx) push_conn_ctx(2278915804) : conn_ctx_stack_ndx = 1 [1970/01/02 09:51:59.345153, 4, pid=18844, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [1970/01/02 09:51:59.345336, 5, pid=18844, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [1970/01/02 09:51:59.345492, 5, pid=18844, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:629(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [1970/01/02 09:51:59.345778, 5, pid=18844, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_tdb.c:594(tdbsam_getsampwnam) pdb_getsampwnam (TDB): error fetching database. Key: USER_root [1970/01/02 09:51:59.346015, 4, pid=18844, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:424(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [1970/01/02 09:51:59.346206, 5, pid=18844, effective(0, 0), real(0, 0), class=passdb] ../source3/passdb/pdb_interface.c:1399(pdb_default_uid_to_sid) pdb_default_uid_to_sid: Did not find user root (0) [1970/01/02 09:51:59.346432, 4, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/sec_ctx.c:424(pop_sec_ctx) pop_sec_ctx (9999, 9999) - sec_ctx_stack_ndx = 0 [1970/01/02 09:51:59.346633, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/passdb/lookup_sid.c:1045(legacy_uid_to_sid) LEGACY: uid 0 -> sid S-1-22-1-0 [1970/01/02 09:51:59.346913, 5, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/passdb/lookup_sid.c:1212(gid_to_sid) gid_to_sid: winbind failed to find a sid for gid 0 [1970/01/02 09:51:59.347097, 4, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/sec_ctx.c:216(push_sec_ctx) push_sec_ctx(9999, 9999) : sec_ctx_stack_ndx = 1 [1970/01/02 09:51:59.347302, 4, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/uid.c:485(push_conn_ctx) push_conn_ctx(2278915804) : conn_ctx_stack_ndx = 0 [1970/01/02 09:51:59.347499, 4, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [1970/01/02 09:51:59.347677, 5, pid=18844, effective(9999, 9999), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [1970/01/02 09:51:59.347836, 5, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/auth/token_util.c:629(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [1970/01/02 09:51:59.348158, 4, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/sec_ctx.c:424(pop_sec_ctx) pop_sec_ctx (9999, 9999) - sec_ctx_stack_ndx = 0 [1970/01/02 09:51:59.348359, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/passdb/lookup_sid.c:1075(legacy_gid_to_sid) LEGACY: gid 0 -> sid S-1-22-2-0 [1970/01/02 09:51:59.348572, 10, pid=18844, effective(9999, 9999), real(0, 0), class=acls] ../source3/smbd/posix_acls.c:2732(canonicalise_acl) canonicalise_acl: Access ace entries before arrange : [1970/01/02 09:51:59.348752, 10, pid=18844, effective(9999, 9999), real(0, 0), class=acls] ../source3/smbd/posix_acls.c:2745(canonicalise_acl) canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms r-x [1970/01/02 09:51:59.349006, 10, pid=18844, effective(9999, 9999), real(0, 0), class=acls] ../source3/smbd/posix_acls.c:2745(canonicalise_acl) canon_ace index 1. Type = allow SID = S-1-22-2-0 gid 0 (root) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms r-x [1970/01/02 09:51:59.349363, 10, pid=18844, effective(9999, 9999), real(0, 0), class=acls] ../source3/smbd/posix_acls.c:2745(canonicalise_acl) canon_ace index 2. Type = allow SID = S-1-22-1-0 uid 0 (root) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx [1970/01/02 09:51:59.349755, 10, pid=18844, effective(9999, 9999), real(0, 0), class=acls] ../source3/smbd/posix_acls.c:848(print_canon_ace_list) print_canon_ace_list: canonicalise_acl: ace entries after arrange canon_ace index 0. Type = allow SID = S-1-22-1-0 uid 0 (root) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx canon_ace index 1. Type = allow SID = S-1-22-2-0 gid 0 (root) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms r-x canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms r-x [1970/01/02 09:51:59.350444, 10, pid=18844, effective(9999, 9999), real(0, 0), class=acls] ../source3/smbd/posix_acls.c:1110(map_canon_ace_perms) map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff [1970/01/02 09:51:59.350651, 10, pid=18844, effective(9999, 9999), real(0, 0), class=acls] ../source3/smbd/posix_acls.c:1110(map_canon_ace_perms) map_canon_ace_perms: Mapped (UNIX) 140 to (NT) 1200a9 [1970/01/02 09:51:59.350845, 10, pid=18844, effective(9999, 9999), real(0, 0), class=acls] ../source3/smbd/posix_acls.c:1110(map_canon_ace_perms) map_canon_ace_perms: Mapped (UNIX) 140 to (NT) 1200a9 [1970/01/02 09:51:59.351067, 5, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/open.c:297(check_parent_access) check_parent_access: access check on directory . for path snmp.txt for mask 0x2 returned (0x2) NT_STATUS_ACCESS_DENIED [1970/01/02 09:51:59.351295, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/open.c:863(open_file) open_file: check_parent_access on file snmp.txt returned NT_STATUS_ACCESS_DENIED [1970/01/02 09:51:59.351505, 5, pid=18844, effective(9999, 9999), real(0, 0)] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) check lock order 1 for /data/samba/lock/smbXsrv_open_global.tdb [1970/01/02 09:51:59.351697, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) lock order: 1:/data/samba/lock/smbXsrv_open_global.tdb 2: 3: [1970/01/02 09:51:59.351933, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) Locking key F6E7667C [1970/01/02 09:51:59.352136, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal) Allocated locked data 0x41c7eb38 [1970/01/02 09:51:59.352373, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) Unlocking key F6E7667C [1970/01/02 09:51:59.352564, 5, pid=18844, effective(9999, 9999), real(0, 0)] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 1 for /data/samba/lock/smbXsrv_open_global.tdb [1970/01/02 09:51:59.352751, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) lock order: 1: 2: 3: [1970/01/02 09:51:59.352984, 5, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/files.c:528(file_free) freed files structure 3009845249 (1 used) [1970/01/02 09:51:59.353184, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/open.c:4094(create_file_unixpath) create_file_unixpath: NT_STATUS_ACCESS_DENIED [1970/01/02 09:51:59.353361, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/open.c:4367(create_file_default) create_file: NT_STATUS_ACCESS_DENIED [1970/01/02 09:51:59.353560, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/smb2_server.c:2598(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] || at ../source3/smbd/smb2_create.c:303 [1970/01/02 09:51:59.353790, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/smb2_server.c:2499(smbd_smb2_request_done_ex) smbd_smb2_request_done_ex: idx[1] status[NT_STATUS_ACCESS_DENIED] body[8] dyn[yes:1] at ../source3/smbd/smb2_server.c:2651 [1970/01/02 09:51:59.354031, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/smb2_server.c:874(smb2_set_operation_credit) smb2_set_operation_credit: requested 1, charge 1, granted 1, current possible/max 482/512, total granted/max/low/range 31/8192/44/31 [1970/01/02 09:51:59.355580, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/smb2_server.c:3257(smbd_smb2_io_handler) smbd_smb2_request idx[1] of 5 vectors [1970/01/02 09:51:59.355819, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/smb2_server.c:621(smb2_validate_sequence_number) smb2_validate_sequence_number: clearing id 44 (position 44) from bitmap [1970/01/02 09:51:59.356033, 10, pid=18844, effective(9999, 9999), real(0, 0)] ../source3/smbd/smb2_server.c:1878(smbd_smb2_request_dispatch) smbd_smb2_request_dispatch: opcode[SMB2_OP_CREATE] mid = 44