Attachment 5588 Details for Bug 6917 – Level 10 debug log during uninherit, copy and apply of ACLs on a file

Level 10 debug log during uninherit, copy and apply of ACLs on a file

samba.log.lab06-3_uninherit_copy_apply.txt (text/plain), 139.33 KB, created by Roel van Meer on 2010-04-02 08:39:35 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Roel van Meer

Created: 2010-04-02 08:39:35 UTC

Size: 139.33 KB

patch

obsolete

>
>[2010/04/02 15:03:46.875112,  3] smbd/process.c:1485(process_smb)
>  Transaction 271 of length 128 (0 toread)
>[2010/04/02 15:03:46.875191,  3] smbd/process.c:1294(switch_message)
>  switch message SMBntcreateX (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.875228,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.875289,  4] smbd/vfs.c:721(vfs_ChDir)
>  vfs_ChDir to /home/vol1/shares/aclshare
>[2010/04/02 15:03:46.875339,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [file-with-acls.txt] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.875366, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [file-with-acls.txt] -> [/home/vol1/shares/aclshare/file-with-acls.txt]
>[2010/04/02 15:03:46.875386,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: file-with-acls.txt reduced to /home/vol1/shares/aclshare/file-with-acls.txt
>[2010/04/02 15:03:46.875406,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [file-with-acls.txt] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.875428, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [file-with-acls.txt] -> [/home/vol1/shares/aclshare/file-with-acls.txt]
>[2010/04/02 15:03:46.875446,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: file-with-acls.txt reduced to /home/vol1/shares/aclshare/file-with-acls.txt
>[2010/04/02 15:03:46.875480,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(file-with-acls.txt) returning 0660
>[2010/04/02 15:03:46.875500,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [file-with-acls.txt] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.875522, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [file-with-acls.txt] -> [/home/vol1/shares/aclshare/file-with-acls.txt]
>[2010/04/02 15:03:46.875540,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: file-with-acls.txt reduced to /home/vol1/shares/aclshare/file-with-acls.txt
>[2010/04/02 15:03:46.875577, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=file-with-acls.txt
>[2010/04/02 15:03:46.875596, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.875655, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 25 for file file-with-acls.txt
>[2010/04/02 15:03:46.875675, 10] smbd/posix_acls.c:564(create_pai_val_v2)
>  create_pai_val_v2: sd_type = 0x8004 num_entries = 3, num_def_entries = 0
>[2010/04/02 15:03:46.875694, 10] smbd/posix_acls.c:408(get_pai_owner_type)
>  get_pai_owner_type: uid = 6683
>[2010/04/02 15:03:46.875736, 10] smbd/posix_acls.c:413(get_pai_owner_type)
>  get_pai_owner_type: gid = 100
>[2010/04/02 15:03:46.875754, 10] smbd/posix_acls.c:417(get_pai_owner_type)
>  get_pai_owner_type: world ace
>[2010/04/02 15:03:46.875771, 10] smbd/posix_acls.c:732(load_inherited_info)
>  load_inherited_info: ACL type 0x8004 for file file-with-acls.txt
>[2010/04/02 15:03:46.875794, 10] smbd/posix_acls.c:158(get_entry_val)
>  get_entry_val: uid = 6683
>[2010/04/02 15:03:46.875812, 10] smbd/posix_acls.c:138(get_pai_entry_val)
>  get_pai_entry_val: uid = 6683
>[2010/04/02 15:03:46.875829, 10] smbd/posix_acls.c:161(get_entry_val)
>  get_entry_val: gid = 100
>[2010/04/02 15:03:46.875846, 10] smbd/posix_acls.c:141(get_pai_entry_val)
>  get_pai_entry_val: gid = 100
>[2010/04/02 15:03:46.875863, 10] smbd/posix_acls.c:165(get_entry_val)
>  get_entry_val: world ace
>[2010/04/02 15:03:46.875880, 10] smbd/posix_acls.c:145(get_pai_entry_val)
>  get_pai_entry_val: world ace
>[2010/04/02 15:03:46.875897, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.875914, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.875939, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.876057, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.876688, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.877175, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.877197, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.877214, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.877243,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.877265,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.877297,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.877340,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.877400,  2] smbd/open.c:631(open_file)
>  leerling1 opened file file-with-acls.txt read=No write=No (numopen=1)
>[2010/04/02 15:03:46.877479, 10] smbd/file_access.c:185(can_access_file_data)
>  can_access_file_data: requesting 0x2 on file file-with-acls.txt
>[2010/04/02 15:03:46.877988,  3] smbd/process.c:1485(process_smb)
>  Transaction 272 of length 76 (0 toread)
>[2010/04/02 15:03:46.878013,  3] smbd/process.c:1294(switch_message)
>  switch message SMBtrans2 (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.878035,  3] smbd/trans2.c:4996(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1006
>[2010/04/02 15:03:46.878070,  3] smbd/trans2.c:5209(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo file-with-acls.txt (fnum = 7674) level=1006 call=7 total_data=0
>[2010/04/02 15:03:46.878540,  3] smbd/process.c:1485(process_smb)
>  Transaction 273 of length 76 (0 toread)
>[2010/04/02 15:03:46.878563,  3] smbd/process.c:1294(switch_message)
>  switch message SMBtrans2 (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.878588,  3] smbd/trans2.c:4996(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1004
>[2010/04/02 15:03:46.878614,  3] smbd/trans2.c:5209(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo file-with-acls.txt (fnum = 7674) level=1004 call=7 total_data=0
>[2010/04/02 15:03:46.879407,  3] smbd/process.c:1485(process_smb)
>  Transaction 274 of length 264 (0 toread)
>[2010/04/02 15:03:46.879430,  3] smbd/process.c:1294(switch_message)
>  switch message SMBnttrans (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.879452,  3] smbd/nttrans.c:1899(call_nt_transact_set_security_desc)
>  call_nt_transact_set_security_desc: file = file-with-acls.txt, sent 0x80000004
>[2010/04/02 15:03:46.879491, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=file-with-acls.txt
>[2010/04/02 15:03:46.879511, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.879528, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.879573, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 25 for file file-with-acls.txt
>[2010/04/02 15:03:46.879592, 10] smbd/posix_acls.c:564(create_pai_val_v2)
>  create_pai_val_v2: sd_type = 0x8004 num_entries = 3, num_def_entries = 0
>[2010/04/02 15:03:46.879610, 10] smbd/posix_acls.c:408(get_pai_owner_type)
>  get_pai_owner_type: uid = 6683
>[2010/04/02 15:03:46.879640, 10] smbd/posix_acls.c:413(get_pai_owner_type)
>  get_pai_owner_type: gid = 100
>[2010/04/02 15:03:46.879657, 10] smbd/posix_acls.c:417(get_pai_owner_type)
>  get_pai_owner_type: world ace
>[2010/04/02 15:03:46.879674, 10] smbd/posix_acls.c:732(load_inherited_info)
>  load_inherited_info: ACL type 0x8004 for file file-with-acls.txt
>[2010/04/02 15:03:46.879694, 10] smbd/posix_acls.c:158(get_entry_val)
>  get_entry_val: uid = 6683
>[2010/04/02 15:03:46.879711, 10] smbd/posix_acls.c:138(get_pai_entry_val)
>  get_pai_entry_val: uid = 6683
>[2010/04/02 15:03:46.879728, 10] smbd/posix_acls.c:161(get_entry_val)
>  get_entry_val: gid = 100
>[2010/04/02 15:03:46.879753, 10] smbd/posix_acls.c:141(get_pai_entry_val)
>  get_pai_entry_val: gid = 100
>[2010/04/02 15:03:46.879771, 10] smbd/posix_acls.c:165(get_entry_val)
>  get_entry_val: world ace
>[2010/04/02 15:03:46.879787, 10] smbd/posix_acls.c:145(get_pai_entry_val)
>  get_pai_entry_val: world ace
>[2010/04/02 15:03:46.879804, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.879821, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.879844, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.879907, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.880353, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.880882, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.880903, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.880920, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.880943,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.880966,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.880984,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.881024,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.881076, 10] smbd/posix_acls.c:3840(set_nt_acl)
>  set_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.881113,  5] smbd/posix_acls.c:1191(unpack_nt_owners)
>  unpack_nt_owners: validating owner_sids.
>[2010/04/02 15:03:46.881131,  3] smbd/posix_acls.c:1213(unpack_nt_owners)
>  unpack_nt_owners: owner sid mapped to uid 6683
>[2010/04/02 15:03:46.881149,  3] smbd/posix_acls.c:1235(unpack_nt_owners)
>  unpack_nt_owners: group sid mapped to gid 100
>[2010/04/02 15:03:46.881166,  5] smbd/posix_acls.c:1238(unpack_nt_owners)
>  unpack_nt_owners: owner_sids validated.
>[2010/04/02 15:03:46.881185, 10] smbd/posix_acls.c:1904(create_canon_ace_lists)
>  create_canon_ace_lists: adding file ACL:
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.881229, 10] smbd/posix_acls.c:1904(create_canon_ace_lists)
>  create_canon_ace_lists: adding file ACL:
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.881664, 10] smbd/posix_acls.c:1904(create_canon_ace_lists)
>  create_canon_ace_lists: adding file ACL:
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.881745, 10] smbd/posix_acls.c:1506(check_owning_objs)
>  check_owning_objs: ACL had owning user/group entries.
>[2010/04/02 15:03:46.881764, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: file ace - before merge
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.882229, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: dir ace - before merge
>[2010/04/02 15:03:46.882250, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: file ace - before deny
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.882689, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: dir ace - before deny
>[2010/04/02 15:03:46.882709, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: file ace - before valid
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.883153, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: dir ace - before valid
>[2010/04/02 15:03:46.883174,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(file-with-acls.txt) returning 0660
>[2010/04/02 15:03:46.883197, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: file ace - return
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.883631, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: dir ace - return
>[2010/04/02 15:03:46.883652, 10] smbd/posix_acls.c:2722(set_canon_ace_list)
>  set_canon_ace_list: setting ACL:
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.884188, 10] smbd/posix_acls.c:2822(set_canon_ace_list)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.884217, 10] smbd/posix_acls.c:2822(set_canon_ace_list)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.884625, 10] smbd/posix_acls.c:2822(set_canon_ace_list)
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.884747,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.884773,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.884791,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.884842,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.884863, 10] smbd/posix_acls.c:194(create_pai_buf_v2)
>  create_pai_buf_v2: num_entries = 3, num_def_entries = 0
>[2010/04/02 15:03:46.884882, 10] smbd/posix_acls.c:212(create_pai_buf_v2)
>  create_pai_buf_v2: sd_type = 0x8004
>[2010/04/02 15:03:46.884900, 10] smbd/posix_acls.c:165(get_entry_val)
>  get_entry_val: world ace
>[2010/04/02 15:03:46.884917, 10] smbd/posix_acls.c:228(create_pai_buf_v2)
>  create_pai_buf_v2: entry 0 [0x0] [0x2] [0xffffffff]
>[2010/04/02 15:03:46.884934, 10] smbd/posix_acls.c:158(get_entry_val)
>  get_entry_val: uid = 6683
>[2010/04/02 15:03:46.884951, 10] smbd/posix_acls.c:228(create_pai_buf_v2)
>  create_pai_buf_v2: entry 1 [0x0] [0x0] [0x1a1b]
>[2010/04/02 15:03:46.884969, 10] smbd/posix_acls.c:161(get_entry_val)
>  get_entry_val: gid = 100
>[2010/04/02 15:03:46.884986, 10] smbd/posix_acls.c:228(create_pai_buf_v2)
>  create_pai_buf_v2: entry 2 [0x0] [0x1] [0x64]
>[2010/04/02 15:03:46.885014, 10] smbd/posix_acls.c:285(store_inheritance_attributes)
>  store_inheritance_attribute: type 0x8004 for file file-with-acls.txt
>[2010/04/02 15:03:46.885037, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.885055, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.885088, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 25 for file file-with-acls.txt
>[2010/04/02 15:03:46.885107, 10] smbd/posix_acls.c:564(create_pai_val_v2)
>  create_pai_val_v2: sd_type = 0x8004 num_entries = 3, num_def_entries = 0
>[2010/04/02 15:03:46.885125, 10] smbd/posix_acls.c:417(get_pai_owner_type)
>  get_pai_owner_type: world ace
>[2010/04/02 15:03:46.885142, 10] smbd/posix_acls.c:408(get_pai_owner_type)
>  get_pai_owner_type: uid = 6683
>[2010/04/02 15:03:46.885159, 10] smbd/posix_acls.c:413(get_pai_owner_type)
>  get_pai_owner_type: gid = 100
>[2010/04/02 15:03:46.885176, 10] smbd/posix_acls.c:732(load_inherited_info)
>  load_inherited_info: ACL type 0x8004 for file file-with-acls.txt
>[2010/04/02 15:03:46.885197, 10] smbd/posix_acls.c:158(get_entry_val)
>  get_entry_val: uid = 6683
>[2010/04/02 15:03:46.885214, 10] smbd/posix_acls.c:138(get_pai_entry_val)
>  get_pai_entry_val: uid = 6683
>[2010/04/02 15:03:46.885231, 10] smbd/posix_acls.c:161(get_entry_val)
>  get_entry_val: gid = 100
>[2010/04/02 15:03:46.885248, 10] smbd/posix_acls.c:141(get_pai_entry_val)
>  get_pai_entry_val: gid = 100
>[2010/04/02 15:03:46.885265, 10] smbd/posix_acls.c:165(get_entry_val)
>  get_entry_val: world ace
>[2010/04/02 15:03:46.885281, 10] smbd/posix_acls.c:145(get_pai_entry_val)
>  get_pai_entry_val: world ace
>[2010/04/02 15:03:46.885298, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.885315, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.885337, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.885395, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.885788, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.886339, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.886360, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.886378, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.886427, 10] modules/vfs_acl_xattr.c:104(store_acl_blob_fsp)
>  store_acl_blob_fsp: storing blob length 256 on file file-with-acls.txt
>[2010/04/02 15:03:46.886448,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.886469,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.886487,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.886531,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.887129,  3] smbd/process.c:1485(process_smb)
>  Transaction 275 of length 45 (0 toread)
>[2010/04/02 15:03:46.887155,  3] smbd/process.c:1294(switch_message)
>  switch message SMBclose (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.887175,  3] smbd/reply.c:4634(reply_close)
>  close fd=-1 fnum=7674 (numopen=1)
>[2010/04/02 15:03:46.887223,  2] smbd/close.c:656(close_normal_file)
>  leerling1 closed file file-with-acls.txt (numopen=0) NT_STATUS_OK
>[2010/04/02 15:03:46.919292,  3] smbd/process.c:1485(process_smb)
>  Transaction 276 of length 110 (0 toread)
>[2010/04/02 15:03:46.919342,  3] smbd/process.c:1294(switch_message)
>  switch message SMBntcreateX (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.919391, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=.
>[2010/04/02 15:03:46.919424, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file .
>[2010/04/02 15:03:46.919462, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 4294967295 for file .
>[2010/04/02 15:03:46.919486, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.919518, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.919542, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.919623, 10] smbd/posix_acls.c:2618(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
>[2010/04/02 15:03:46.919671, 10] smbd/posix_acls.c:841(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-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.919782, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.919801, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.919833, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.919858,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.919882,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.919899,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.919935,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.919955, 10] ./modules/vfs_acl_common.c:289(get_nt_acl_internal)
>  get_nt_acl_internal: get_acl_blob returned NT_STATUS_NOT_FOUND
>[2010/04/02 15:03:46.919979,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0770
>[2010/04/02 15:03:46.919998,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0660
>[2010/04/02 15:03:46.920014, 10] ./modules/vfs_acl_common.c:200(add_directory_inheritable_components)
>  add_directory_inheritable_components: directory ., mode = 0770
>[2010/04/02 15:03:46.920058, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.920077, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.920094, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.920136,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [Thumbs.db] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.920170, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [Thumbs.db] -> [/home/vol1/shares/aclshare/Thumbs.db]
>[2010/04/02 15:03:46.920188,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: Thumbs.db reduced to /home/vol1/shares/aclshare/Thumbs.db
>[2010/04/02 15:03:46.920213,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [Thumbs.db] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.920240, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [Thumbs.db] -> [/home/vol1/shares/aclshare/Thumbs.db]
>[2010/04/02 15:03:46.920257,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: Thumbs.db reduced to /home/vol1/shares/aclshare/Thumbs.db
>[2010/04/02 15:03:46.920280,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(Thumbs.db) returning 0660
>[2010/04/02 15:03:46.920303,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [Thumbs.db] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.920330, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [Thumbs.db] -> [/home/vol1/shares/aclshare/Thumbs.db]
>[2010/04/02 15:03:46.920348,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: Thumbs.db reduced to /home/vol1/shares/aclshare/Thumbs.db
>[2010/04/02 15:03:46.920370,  3] smbd/error.c:80(error_packet_set)
>  error packet at smbd/error.c(160) cmd=162 (SMBntcreateX) NT_STATUS_OBJECT_NAME_NOT_FOUND
>[2010/04/02 15:03:46.922841,  3] smbd/process.c:1485(process_smb)
>  Transaction 277 of length 128 (0 toread)
>[2010/04/02 15:03:46.922866,  3] smbd/process.c:1294(switch_message)
>  switch message SMBntcreateX (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.922892,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [file-with-acls.txt] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.922931, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [file-with-acls.txt] -> [/home/vol1/shares/aclshare/file-with-acls.txt]
>[2010/04/02 15:03:46.922950,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: file-with-acls.txt reduced to /home/vol1/shares/aclshare/file-with-acls.txt
>[2010/04/02 15:03:46.922983,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [file-with-acls.txt] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.923006, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [file-with-acls.txt] -> [/home/vol1/shares/aclshare/file-with-acls.txt]
>[2010/04/02 15:03:46.923024,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: file-with-acls.txt reduced to /home/vol1/shares/aclshare/file-with-acls.txt
>[2010/04/02 15:03:46.923056,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(file-with-acls.txt) returning 0660
>[2010/04/02 15:03:46.923075,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [file-with-acls.txt] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.923097, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [file-with-acls.txt] -> [/home/vol1/shares/aclshare/file-with-acls.txt]
>[2010/04/02 15:03:46.923115,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: file-with-acls.txt reduced to /home/vol1/shares/aclshare/file-with-acls.txt
>[2010/04/02 15:03:46.923147, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=file-with-acls.txt
>[2010/04/02 15:03:46.923165, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.923197, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 25 for file file-with-acls.txt
>[2010/04/02 15:03:46.923232, 10] smbd/posix_acls.c:564(create_pai_val_v2)
>  create_pai_val_v2: sd_type = 0x8004 num_entries = 3, num_def_entries = 0
>[2010/04/02 15:03:46.923251, 10] smbd/posix_acls.c:417(get_pai_owner_type)
>  get_pai_owner_type: world ace
>[2010/04/02 15:03:46.923267, 10] smbd/posix_acls.c:408(get_pai_owner_type)
>  get_pai_owner_type: uid = 6683
>[2010/04/02 15:03:46.923284, 10] smbd/posix_acls.c:413(get_pai_owner_type)
>  get_pai_owner_type: gid = 100
>[2010/04/02 15:03:46.923300, 10] smbd/posix_acls.c:732(load_inherited_info)
>  load_inherited_info: ACL type 0x8004 for file file-with-acls.txt
>[2010/04/02 15:03:46.923320, 10] smbd/posix_acls.c:158(get_entry_val)
>  get_entry_val: uid = 6683
>[2010/04/02 15:03:46.923337, 10] smbd/posix_acls.c:138(get_pai_entry_val)
>  get_pai_entry_val: uid = 6683
>[2010/04/02 15:03:46.923356, 10] smbd/posix_acls.c:161(get_entry_val)
>  get_entry_val: gid = 100
>[2010/04/02 15:03:46.923379, 10] smbd/posix_acls.c:141(get_pai_entry_val)
>  get_pai_entry_val: gid = 100
>[2010/04/02 15:03:46.923396, 10] smbd/posix_acls.c:165(get_entry_val)
>  get_entry_val: world ace
>[2010/04/02 15:03:46.923413, 10] smbd/posix_acls.c:145(get_pai_entry_val)
>  get_pai_entry_val: world ace
>[2010/04/02 15:03:46.923429, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.923446, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.923469, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.923535, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.924065, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.924816, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.924850, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.924868, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.924910,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.924951,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.924970,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.925009,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.925091,  2] smbd/open.c:631(open_file)
>  leerling1 opened file file-with-acls.txt read=No write=No (numopen=1)
>[2010/04/02 15:03:46.925144, 10] smbd/file_access.c:185(can_access_file_data)
>  can_access_file_data: requesting 0x2 on file file-with-acls.txt
>[2010/04/02 15:03:46.925588,  3] smbd/process.c:1485(process_smb)
>  Transaction 278 of length 88 (0 toread)
>[2010/04/02 15:03:46.925612,  3] smbd/process.c:1294(switch_message)
>  switch message SMBnttrans (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.925634,  3] smbd/nttrans.c:1786(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: file = file-with-acls.txt, info_wanted = 0x4
>[2010/04/02 15:03:46.925654, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=file-with-acls.txt
>[2010/04/02 15:03:46.925672, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.925689, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.925745, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 25 for file file-with-acls.txt
>[2010/04/02 15:03:46.925764, 10] smbd/posix_acls.c:564(create_pai_val_v2)
>  create_pai_val_v2: sd_type = 0x8004 num_entries = 3, num_def_entries = 0
>[2010/04/02 15:03:46.925783, 10] smbd/posix_acls.c:417(get_pai_owner_type)
>  get_pai_owner_type: world ace
>[2010/04/02 15:03:46.925799, 10] smbd/posix_acls.c:408(get_pai_owner_type)
>  get_pai_owner_type: uid = 6683
>[2010/04/02 15:03:46.925816, 10] smbd/posix_acls.c:413(get_pai_owner_type)
>  get_pai_owner_type: gid = 100
>[2010/04/02 15:03:46.925832, 10] smbd/posix_acls.c:732(load_inherited_info)
>  load_inherited_info: ACL type 0x8004 for file file-with-acls.txt
>[2010/04/02 15:03:46.925867, 10] smbd/posix_acls.c:158(get_entry_val)
>  get_entry_val: uid = 6683
>[2010/04/02 15:03:46.925885, 10] smbd/posix_acls.c:138(get_pai_entry_val)
>  get_pai_entry_val: uid = 6683
>[2010/04/02 15:03:46.925902, 10] smbd/posix_acls.c:161(get_entry_val)
>  get_entry_val: gid = 100
>[2010/04/02 15:03:46.925919, 10] smbd/posix_acls.c:141(get_pai_entry_val)
>  get_pai_entry_val: gid = 100
>[2010/04/02 15:03:46.925936, 10] smbd/posix_acls.c:165(get_entry_val)
>  get_entry_val: world ace
>[2010/04/02 15:03:46.925952, 10] smbd/posix_acls.c:145(get_pai_entry_val)
>  get_pai_entry_val: world ace
>[2010/04/02 15:03:46.925969, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.925986, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.926023, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.926083, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.926530, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.927026, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.927058, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.927076, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.927114,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.927141,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.927159,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.927194,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.927251,  3] smbd/nttrans.c:1820(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: sd_size = 120.
>[2010/04/02 15:03:46.927277,  3] smbd/error.c:80(error_packet_set)
>  error packet at smbd/nttrans.c(240) cmd=160 (SMBnttrans) NT_STATUS_BUFFER_TOO_SMALL
>[2010/04/02 15:03:46.927659,  3] smbd/process.c:1485(process_smb)
>  Transaction 279 of length 88 (0 toread)
>[2010/04/02 15:03:46.927681,  3] smbd/process.c:1294(switch_message)
>  switch message SMBnttrans (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.927701,  3] smbd/nttrans.c:1786(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: file = file-with-acls.txt, info_wanted = 0x4
>[2010/04/02 15:03:46.927721, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=file-with-acls.txt
>[2010/04/02 15:03:46.927738, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.927755, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.927803, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 25 for file file-with-acls.txt
>[2010/04/02 15:03:46.927823, 10] smbd/posix_acls.c:564(create_pai_val_v2)
>  create_pai_val_v2: sd_type = 0x8004 num_entries = 3, num_def_entries = 0
>[2010/04/02 15:03:46.927840, 10] smbd/posix_acls.c:417(get_pai_owner_type)
>  get_pai_owner_type: world ace
>[2010/04/02 15:03:46.927857, 10] smbd/posix_acls.c:408(get_pai_owner_type)
>  get_pai_owner_type: uid = 6683
>[2010/04/02 15:03:46.927873, 10] smbd/posix_acls.c:413(get_pai_owner_type)
>  get_pai_owner_type: gid = 100
>[2010/04/02 15:03:46.927889, 10] smbd/posix_acls.c:732(load_inherited_info)
>  load_inherited_info: ACL type 0x8004 for file file-with-acls.txt
>[2010/04/02 15:03:46.927909, 10] smbd/posix_acls.c:158(get_entry_val)
>  get_entry_val: uid = 6683
>[2010/04/02 15:03:46.927992, 10] smbd/posix_acls.c:138(get_pai_entry_val)
>  get_pai_entry_val: uid = 6683
>[2010/04/02 15:03:46.928012, 10] smbd/posix_acls.c:161(get_entry_val)
>  get_entry_val: gid = 100
>[2010/04/02 15:03:46.928029, 10] smbd/posix_acls.c:141(get_pai_entry_val)
>  get_pai_entry_val: gid = 100
>[2010/04/02 15:03:46.928047, 10] smbd/posix_acls.c:165(get_entry_val)
>  get_entry_val: world ace
>[2010/04/02 15:03:46.928078, 10] smbd/posix_acls.c:145(get_pai_entry_val)
>  get_pai_entry_val: world ace
>[2010/04/02 15:03:46.928095, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.928116, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.928139, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.928211, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.928596, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.929085, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.929110, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.929128, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.929150,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.929173,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.929190,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.929238,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.929296,  3] smbd/nttrans.c:1820(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: sd_size = 120.
>[2010/04/02 15:03:46.929845,  3] smbd/process.c:1485(process_smb)
>  Transaction 280 of length 92 (0 toread)
>[2010/04/02 15:03:46.929869,  3] smbd/process.c:1294(switch_message)
>  switch message SMBntcreateX (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.929894,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [.] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.929916, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.929934,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: . reduced to /home/vol1/shares/aclshare
>[2010/04/02 15:03:46.929952,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [.] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.929987, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.930004,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: . reduced to /home/vol1/shares/aclshare
>[2010/04/02 15:03:46.930027,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0660
>[2010/04/02 15:03:46.930045,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [.] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.930068, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.930086,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: . reduced to /home/vol1/shares/aclshare
>[2010/04/02 15:03:46.930119, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=.
>[2010/04/02 15:03:46.930137, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file .
>[2010/04/02 15:03:46.930169, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 4294967295 for file .
>[2010/04/02 15:03:46.930191, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.930208, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.930231, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.930294, 10] smbd/posix_acls.c:2618(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
>[2010/04/02 15:03:46.930339, 10] smbd/posix_acls.c:841(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-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.930454, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.930473, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.930490, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.930512,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.930549,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.930567,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.930599,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.930639, 10] ./modules/vfs_acl_common.c:289(get_nt_acl_internal)
>  get_nt_acl_internal: get_acl_blob returned NT_STATUS_NOT_FOUND
>[2010/04/02 15:03:46.930664,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0770
>[2010/04/02 15:03:46.930682,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0660
>[2010/04/02 15:03:46.930699, 10] ./modules/vfs_acl_common.c:200(add_directory_inheritable_components)
>  add_directory_inheritable_components: directory ., mode = 0770
>[2010/04/02 15:03:46.930717, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.930734, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.930765, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.930793, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=.
>[2010/04/02 15:03:46.930811, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file .
>[2010/04/02 15:03:46.930841, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 4294967295 for file .
>[2010/04/02 15:03:46.930863, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.930880, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.930903, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.930975, 10] smbd/posix_acls.c:2618(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
>[2010/04/02 15:03:46.931033, 10] smbd/posix_acls.c:841(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-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.931143, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.931162, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.931179, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.931203,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.931252,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.931270,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.931303,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.931322, 10] ./modules/vfs_acl_common.c:289(get_nt_acl_internal)
>  get_nt_acl_internal: get_acl_blob returned NT_STATUS_NOT_FOUND
>[2010/04/02 15:03:46.931345,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0770
>[2010/04/02 15:03:46.931378,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0660
>[2010/04/02 15:03:46.931396, 10] ./modules/vfs_acl_common.c:200(add_directory_inheritable_components)
>  add_directory_inheritable_components: directory ., mode = 0770
>[2010/04/02 15:03:46.931413, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.931431, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.931448, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.931808,  3] smbd/process.c:1485(process_smb)
>  Transaction 281 of length 76 (0 toread)
>[2010/04/02 15:03:46.931839,  3] smbd/process.c:1294(switch_message)
>  switch message SMBtrans2 (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.931861,  3] smbd/trans2.c:4996(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1006
>[2010/04/02 15:03:46.931889,  3] smbd/trans2.c:5209(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo . (fnum = 7678) level=1006 call=7 total_data=0
>[2010/04/02 15:03:46.932231,  3] smbd/process.c:1485(process_smb)
>  Transaction 282 of length 88 (0 toread)
>[2010/04/02 15:03:46.932267,  3] smbd/process.c:1294(switch_message)
>  switch message SMBnttrans (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.932287,  3] smbd/nttrans.c:1786(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: file = ., info_wanted = 0x4
>[2010/04/02 15:03:46.932307, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=.
>[2010/04/02 15:03:46.932324, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file .
>[2010/04/02 15:03:46.932341, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file .
>[2010/04/02 15:03:46.932371, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 4294967295 for file .
>[2010/04/02 15:03:46.932400, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.932418, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.932441, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.932514, 10] smbd/posix_acls.c:2618(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
>[2010/04/02 15:03:46.932560, 10] smbd/posix_acls.c:841(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-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.932670, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.932689, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.932719, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.932741,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.932762,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.932779,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.932811,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.932829, 10] ./modules/vfs_acl_common.c:289(get_nt_acl_internal)
>  get_nt_acl_internal: get_acl_blob returned NT_STATUS_NOT_FOUND
>[2010/04/02 15:03:46.932847,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0770
>[2010/04/02 15:03:46.932865,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0660
>[2010/04/02 15:03:46.932881, 10] ./modules/vfs_acl_common.c:200(add_directory_inheritable_components)
>  add_directory_inheritable_components: directory ., mode = 0770
>[2010/04/02 15:03:46.932898, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.932929, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.932946, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.932963,  3] smbd/nttrans.c:1820(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: sd_size = 168.
>[2010/04/02 15:03:46.932997,  3] smbd/error.c:80(error_packet_set)
>  error packet at smbd/nttrans.c(240) cmd=160 (SMBnttrans) NT_STATUS_BUFFER_TOO_SMALL
>[2010/04/02 15:03:46.933382,  3] smbd/process.c:1485(process_smb)
>  Transaction 283 of length 88 (0 toread)
>[2010/04/02 15:03:46.933403,  3] smbd/process.c:1294(switch_message)
>  switch message SMBnttrans (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.933422,  3] smbd/nttrans.c:1786(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: file = ., info_wanted = 0x4
>[2010/04/02 15:03:46.933440, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=.
>[2010/04/02 15:03:46.933456, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file .
>[2010/04/02 15:03:46.933480, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file .
>[2010/04/02 15:03:46.933509, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 4294967295 for file .
>[2010/04/02 15:03:46.933529, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.933546, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.933568, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.933624, 10] smbd/posix_acls.c:2618(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
>[2010/04/02 15:03:46.933668, 10] smbd/posix_acls.c:841(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-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.933789, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.933808, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.933836, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.933858,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.933879,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.933896,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.933935,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.933954, 10] ./modules/vfs_acl_common.c:289(get_nt_acl_internal)
>  get_nt_acl_internal: get_acl_blob returned NT_STATUS_NOT_FOUND
>[2010/04/02 15:03:46.933972,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0770
>[2010/04/02 15:03:46.933989,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0660
>[2010/04/02 15:03:46.934005, 10] ./modules/vfs_acl_common.c:200(add_directory_inheritable_components)
>  add_directory_inheritable_components: directory ., mode = 0770
>[2010/04/02 15:03:46.934023, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.934039, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.934055, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.934126,  3] smbd/nttrans.c:1820(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: sd_size = 168.
>[2010/04/02 15:03:46.934504,  3] smbd/process.c:1485(process_smb)
>  Transaction 284 of length 45 (0 toread)
>[2010/04/02 15:03:46.934528,  3] smbd/process.c:1294(switch_message)
>  switch message SMBclose (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.934547,  3] smbd/reply.c:4624(reply_close)
>  close directory fnum=7678
>[2010/04/02 15:03:46.934916,  3] smbd/process.c:1485(process_smb)
>  Transaction 285 of length 88 (0 toread)
>[2010/04/02 15:03:46.934938,  3] smbd/process.c:1294(switch_message)
>  switch message SMBnttrans (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.934961,  3] smbd/nttrans.c:1786(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: file = file-with-acls.txt, info_wanted = 0x7
>[2010/04/02 15:03:46.934980, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=file-with-acls.txt
>[2010/04/02 15:03:46.935011, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.935029, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.935060, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 25 for file file-with-acls.txt
>[2010/04/02 15:03:46.935083, 10] smbd/posix_acls.c:564(create_pai_val_v2)
>  create_pai_val_v2: sd_type = 0x8004 num_entries = 3, num_def_entries = 0
>[2010/04/02 15:03:46.935101, 10] smbd/posix_acls.c:417(get_pai_owner_type)
>  get_pai_owner_type: world ace
>[2010/04/02 15:03:46.935116, 10] smbd/posix_acls.c:408(get_pai_owner_type)
>  get_pai_owner_type: uid = 6683
>[2010/04/02 15:03:46.935132, 10] smbd/posix_acls.c:413(get_pai_owner_type)
>  get_pai_owner_type: gid = 100
>[2010/04/02 15:03:46.935148, 10] smbd/posix_acls.c:732(load_inherited_info)
>  load_inherited_info: ACL type 0x8004 for file file-with-acls.txt
>[2010/04/02 15:03:46.935167, 10] smbd/posix_acls.c:158(get_entry_val)
>  get_entry_val: uid = 6683
>[2010/04/02 15:03:46.935184, 10] smbd/posix_acls.c:138(get_pai_entry_val)
>  get_pai_entry_val: uid = 6683
>[2010/04/02 15:03:46.935200, 10] smbd/posix_acls.c:161(get_entry_val)
>  get_entry_val: gid = 100
>[2010/04/02 15:03:46.935217, 10] smbd/posix_acls.c:141(get_pai_entry_val)
>  get_pai_entry_val: gid = 100
>[2010/04/02 15:03:46.935233, 10] smbd/posix_acls.c:165(get_entry_val)
>  get_entry_val: world ace
>[2010/04/02 15:03:46.935249, 10] smbd/posix_acls.c:145(get_pai_entry_val)
>  get_pai_entry_val: world ace
>[2010/04/02 15:03:46.935280, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.935297, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.935319, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.935386, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.935847, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.936392, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.936412, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.936429, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.936451,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.936473,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.936490,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.936536,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.936581,  3] smbd/nttrans.c:1820(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: sd_size = 176.
>[2010/04/02 15:03:46.936601,  3] smbd/error.c:80(error_packet_set)
>  error packet at smbd/nttrans.c(240) cmd=160 (SMBnttrans) NT_STATUS_BUFFER_TOO_SMALL
>[2010/04/02 15:03:46.936889,  3] smbd/process.c:1485(process_smb)
>  Transaction 286 of length 88 (0 toread)
>[2010/04/02 15:03:46.936917,  3] smbd/process.c:1294(switch_message)
>  switch message SMBnttrans (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.936937,  3] smbd/nttrans.c:1786(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: file = file-with-acls.txt, info_wanted = 0x7
>[2010/04/02 15:03:46.936955, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=file-with-acls.txt
>[2010/04/02 15:03:46.936972, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.936989, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.937020, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 25 for file file-with-acls.txt
>[2010/04/02 15:03:46.937039, 10] smbd/posix_acls.c:564(create_pai_val_v2)
>  create_pai_val_v2: sd_type = 0x8004 num_entries = 3, num_def_entries = 0
>[2010/04/02 15:03:46.937055, 10] smbd/posix_acls.c:417(get_pai_owner_type)
>  get_pai_owner_type: world ace
>[2010/04/02 15:03:46.937071, 10] smbd/posix_acls.c:408(get_pai_owner_type)
>  get_pai_owner_type: uid = 6683
>[2010/04/02 15:03:46.937087, 10] smbd/posix_acls.c:413(get_pai_owner_type)
>  get_pai_owner_type: gid = 100
>[2010/04/02 15:03:46.937103, 10] smbd/posix_acls.c:732(load_inherited_info)
>  load_inherited_info: ACL type 0x8004 for file file-with-acls.txt
>[2010/04/02 15:03:46.937143, 10] smbd/posix_acls.c:158(get_entry_val)
>  get_entry_val: uid = 6683
>[2010/04/02 15:03:46.937171, 10] smbd/posix_acls.c:138(get_pai_entry_val)
>  get_pai_entry_val: uid = 6683
>[2010/04/02 15:03:46.937189, 10] smbd/posix_acls.c:161(get_entry_val)
>  get_entry_val: gid = 100
>[2010/04/02 15:03:46.937205, 10] smbd/posix_acls.c:141(get_pai_entry_val)
>  get_pai_entry_val: gid = 100
>[2010/04/02 15:03:46.937222, 10] smbd/posix_acls.c:165(get_entry_val)
>  get_entry_val: world ace
>[2010/04/02 15:03:46.937237, 10] smbd/posix_acls.c:145(get_pai_entry_val)
>  get_pai_entry_val: world ace
>[2010/04/02 15:03:46.937267, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.937284, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.937306, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.937363, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.937751, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.938198, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.938218, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.938235, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.938265,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.938287,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.938304,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.938352,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.938396,  3] smbd/nttrans.c:1820(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: sd_size = 176.
>[2010/04/02 15:03:46.938812,  3] smbd/process.c:1485(process_smb)
>  Transaction 287 of length 76 (0 toread)
>[2010/04/02 15:03:46.938839,  3] smbd/process.c:1294(switch_message)
>  switch message SMBtrans2 (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.938859,  3] smbd/trans2.c:4996(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1004
>[2010/04/02 15:03:46.938887,  3] smbd/trans2.c:5209(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo file-with-acls.txt (fnum = 7676) level=1004 call=7 total_data=0
>[2010/04/02 15:03:46.939358,  3] smbd/process.c:1485(process_smb)
>  Transaction 288 of length 45 (0 toread)
>[2010/04/02 15:03:46.939378,  3] smbd/process.c:1294(switch_message)
>  switch message SMBclose (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.939397,  3] smbd/reply.c:4634(reply_close)
>  close fd=-1 fnum=7676 (numopen=1)
>[2010/04/02 15:03:46.939428,  2] smbd/close.c:656(close_normal_file)
>  leerling1 closed file file-with-acls.txt (numopen=0) NT_STATUS_OK
>[2010/04/02 15:03:46.942976,  3] smbd/process.c:1485(process_smb)
>  Transaction 289 of length 128 (0 toread)
>[2010/04/02 15:03:46.943001,  3] smbd/process.c:1294(switch_message)
>  switch message SMBntcreateX (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.943028,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [file-with-acls.txt] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.943072, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [file-with-acls.txt] -> [/home/vol1/shares/aclshare/file-with-acls.txt]
>[2010/04/02 15:03:46.943091,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: file-with-acls.txt reduced to /home/vol1/shares/aclshare/file-with-acls.txt
>[2010/04/02 15:03:46.943109,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [file-with-acls.txt] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.943131, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [file-with-acls.txt] -> [/home/vol1/shares/aclshare/file-with-acls.txt]
>[2010/04/02 15:03:46.943148,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: file-with-acls.txt reduced to /home/vol1/shares/aclshare/file-with-acls.txt
>[2010/04/02 15:03:46.943183,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(file-with-acls.txt) returning 0660
>[2010/04/02 15:03:46.943202,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [file-with-acls.txt] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.943224, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [file-with-acls.txt] -> [/home/vol1/shares/aclshare/file-with-acls.txt]
>[2010/04/02 15:03:46.943241,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: file-with-acls.txt reduced to /home/vol1/shares/aclshare/file-with-acls.txt
>[2010/04/02 15:03:46.943282, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=file-with-acls.txt
>[2010/04/02 15:03:46.943300, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.943337, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 25 for file file-with-acls.txt
>[2010/04/02 15:03:46.943355, 10] smbd/posix_acls.c:564(create_pai_val_v2)
>  create_pai_val_v2: sd_type = 0x8004 num_entries = 3, num_def_entries = 0
>[2010/04/02 15:03:46.943373, 10] smbd/posix_acls.c:417(get_pai_owner_type)
>  get_pai_owner_type: world ace
>[2010/04/02 15:03:46.943389, 10] smbd/posix_acls.c:408(get_pai_owner_type)
>  get_pai_owner_type: uid = 6683
>[2010/04/02 15:03:46.943405, 10] smbd/posix_acls.c:413(get_pai_owner_type)
>  get_pai_owner_type: gid = 100
>[2010/04/02 15:03:46.943421, 10] smbd/posix_acls.c:732(load_inherited_info)
>  load_inherited_info: ACL type 0x8004 for file file-with-acls.txt
>[2010/04/02 15:03:46.943440, 10] smbd/posix_acls.c:158(get_entry_val)
>  get_entry_val: uid = 6683
>[2010/04/02 15:03:46.943456, 10] smbd/posix_acls.c:138(get_pai_entry_val)
>  get_pai_entry_val: uid = 6683
>[2010/04/02 15:03:46.943473, 10] smbd/posix_acls.c:161(get_entry_val)
>  get_entry_val: gid = 100
>[2010/04/02 15:03:46.943489, 10] smbd/posix_acls.c:141(get_pai_entry_val)
>  get_pai_entry_val: gid = 100
>[2010/04/02 15:03:46.943506, 10] smbd/posix_acls.c:165(get_entry_val)
>  get_entry_val: world ace
>[2010/04/02 15:03:46.943521, 10] smbd/posix_acls.c:145(get_pai_entry_val)
>  get_pai_entry_val: world ace
>[2010/04/02 15:03:46.943537, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.943553, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.943589, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.943654, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.944039, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.944554, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.944574, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.944606, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.944631,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.944652,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.944669,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.944702,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.944754,  2] smbd/open.c:631(open_file)
>  leerling1 opened file file-with-acls.txt read=No write=No (numopen=1)
>[2010/04/02 15:03:46.944798, 10] smbd/file_access.c:185(can_access_file_data)
>  can_access_file_data: requesting 0x2 on file file-with-acls.txt
>[2010/04/02 15:03:46.945450,  3] smbd/process.c:1485(process_smb)
>  Transaction 290 of length 88 (0 toread)
>[2010/04/02 15:03:46.945475,  3] smbd/process.c:1294(switch_message)
>  switch message SMBnttrans (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.945495,  3] smbd/nttrans.c:1786(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: file = file-with-acls.txt, info_wanted = 0x4
>[2010/04/02 15:03:46.945514, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=file-with-acls.txt
>[2010/04/02 15:03:46.945545, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.945562, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.945613, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 25 for file file-with-acls.txt
>[2010/04/02 15:03:46.945632, 10] smbd/posix_acls.c:564(create_pai_val_v2)
>  create_pai_val_v2: sd_type = 0x8004 num_entries = 3, num_def_entries = 0
>[2010/04/02 15:03:46.945669, 10] smbd/posix_acls.c:417(get_pai_owner_type)
>  get_pai_owner_type: world ace
>[2010/04/02 15:03:46.945685, 10] smbd/posix_acls.c:408(get_pai_owner_type)
>  get_pai_owner_type: uid = 6683
>[2010/04/02 15:03:46.945706, 10] smbd/posix_acls.c:413(get_pai_owner_type)
>  get_pai_owner_type: gid = 100
>[2010/04/02 15:03:46.945721, 10] smbd/posix_acls.c:732(load_inherited_info)
>  load_inherited_info: ACL type 0x8004 for file file-with-acls.txt
>[2010/04/02 15:03:46.945740, 10] smbd/posix_acls.c:158(get_entry_val)
>  get_entry_val: uid = 6683
>[2010/04/02 15:03:46.945757, 10] smbd/posix_acls.c:138(get_pai_entry_val)
>  get_pai_entry_val: uid = 6683
>[2010/04/02 15:03:46.945773, 10] smbd/posix_acls.c:161(get_entry_val)
>  get_entry_val: gid = 100
>[2010/04/02 15:03:46.945789, 10] smbd/posix_acls.c:141(get_pai_entry_val)
>  get_pai_entry_val: gid = 100
>[2010/04/02 15:03:46.945820, 10] smbd/posix_acls.c:165(get_entry_val)
>  get_entry_val: world ace
>[2010/04/02 15:03:46.945836, 10] smbd/posix_acls.c:145(get_pai_entry_val)
>  get_pai_entry_val: world ace
>[2010/04/02 15:03:46.945853, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.945869, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.945891, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.945974, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.946361, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.946949, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.946969, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.946986, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.947008,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.947030,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.947047,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.947081,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.947123,  3] smbd/nttrans.c:1820(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: sd_size = 120.
>[2010/04/02 15:03:46.947160,  3] smbd/error.c:80(error_packet_set)
>  error packet at smbd/nttrans.c(240) cmd=160 (SMBnttrans) NT_STATUS_BUFFER_TOO_SMALL
>[2010/04/02 15:03:46.947457,  3] smbd/process.c:1485(process_smb)
>  Transaction 291 of length 88 (0 toread)
>[2010/04/02 15:03:46.947482,  3] smbd/process.c:1294(switch_message)
>  switch message SMBnttrans (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.947502,  3] smbd/nttrans.c:1786(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: file = file-with-acls.txt, info_wanted = 0x4
>[2010/04/02 15:03:46.947521, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=file-with-acls.txt
>[2010/04/02 15:03:46.947549, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.947568, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.947598, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 25 for file file-with-acls.txt
>[2010/04/02 15:03:46.947616, 10] smbd/posix_acls.c:564(create_pai_val_v2)
>  create_pai_val_v2: sd_type = 0x8004 num_entries = 3, num_def_entries = 0
>[2010/04/02 15:03:46.947654, 10] smbd/posix_acls.c:417(get_pai_owner_type)
>  get_pai_owner_type: world ace
>[2010/04/02 15:03:46.947670, 10] smbd/posix_acls.c:408(get_pai_owner_type)
>  get_pai_owner_type: uid = 6683
>[2010/04/02 15:03:46.947686, 10] smbd/posix_acls.c:413(get_pai_owner_type)
>  get_pai_owner_type: gid = 100
>[2010/04/02 15:03:46.947716, 10] smbd/posix_acls.c:732(load_inherited_info)
>  load_inherited_info: ACL type 0x8004 for file file-with-acls.txt
>[2010/04/02 15:03:46.947735, 10] smbd/posix_acls.c:158(get_entry_val)
>  get_entry_val: uid = 6683
>[2010/04/02 15:03:46.947751, 10] smbd/posix_acls.c:138(get_pai_entry_val)
>  get_pai_entry_val: uid = 6683
>[2010/04/02 15:03:46.947768, 10] smbd/posix_acls.c:161(get_entry_val)
>  get_entry_val: gid = 100
>[2010/04/02 15:03:46.947784, 10] smbd/posix_acls.c:141(get_pai_entry_val)
>  get_pai_entry_val: gid = 100
>[2010/04/02 15:03:46.947800, 10] smbd/posix_acls.c:165(get_entry_val)
>  get_entry_val: world ace
>[2010/04/02 15:03:46.947816, 10] smbd/posix_acls.c:145(get_pai_entry_val)
>  get_pai_entry_val: world ace
>[2010/04/02 15:03:46.947843, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.947861, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.947902, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.947960, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.948345, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.948862, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.948897, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.948914, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.948936,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.948958,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.948975,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.949009,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.949059,  3] smbd/nttrans.c:1820(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: sd_size = 120.
>[2010/04/02 15:03:46.949503,  3] smbd/process.c:1485(process_smb)
>  Transaction 292 of length 92 (0 toread)
>[2010/04/02 15:03:46.949526,  3] smbd/process.c:1294(switch_message)
>  switch message SMBntcreateX (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.949550,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [.] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.949571, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.949614,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: . reduced to /home/vol1/shares/aclshare
>[2010/04/02 15:03:46.949634,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [.] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.949653, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.949669,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: . reduced to /home/vol1/shares/aclshare
>[2010/04/02 15:03:46.949710,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0660
>[2010/04/02 15:03:46.949729,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [.] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.949748, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.949764,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: . reduced to /home/vol1/shares/aclshare
>[2010/04/02 15:03:46.949790, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=.
>[2010/04/02 15:03:46.949807, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file .
>[2010/04/02 15:03:46.949844, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 4294967295 for file .
>[2010/04/02 15:03:46.949877, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.949894, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.949917, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.949973, 10] smbd/posix_acls.c:2618(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
>[2010/04/02 15:03:46.950085, 10] smbd/posix_acls.c:841(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-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.950198, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.950217, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.950234, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.950255,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.950277,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.950301,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.950332,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.950351, 10] ./modules/vfs_acl_common.c:289(get_nt_acl_internal)
>  get_nt_acl_internal: get_acl_blob returned NT_STATUS_NOT_FOUND
>[2010/04/02 15:03:46.950374,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0770
>[2010/04/02 15:03:46.950391,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0660
>[2010/04/02 15:03:46.950408, 10] ./modules/vfs_acl_common.c:200(add_directory_inheritable_components)
>  add_directory_inheritable_components: directory ., mode = 0770
>[2010/04/02 15:03:46.950439, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.950457, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.950535, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.950562, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=.
>[2010/04/02 15:03:46.950579, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file .
>[2010/04/02 15:03:46.950608, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 4294967295 for file .
>[2010/04/02 15:03:46.950637, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.950654, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.950676, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.950732, 10] smbd/posix_acls.c:2618(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
>[2010/04/02 15:03:46.950777, 10] smbd/posix_acls.c:841(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-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.950978, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.950997, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.951014, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.951037,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.951057,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.951083,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.951114,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.951133, 10] ./modules/vfs_acl_common.c:289(get_nt_acl_internal)
>  get_nt_acl_internal: get_acl_blob returned NT_STATUS_NOT_FOUND
>[2010/04/02 15:03:46.951156,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0770
>[2010/04/02 15:03:46.951173,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0660
>[2010/04/02 15:03:46.951190, 10] ./modules/vfs_acl_common.c:200(add_directory_inheritable_components)
>  add_directory_inheritable_components: directory ., mode = 0770
>[2010/04/02 15:03:46.951207, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.951224, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.951256, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.951763,  3] smbd/process.c:1485(process_smb)
>  Transaction 293 of length 88 (0 toread)
>[2010/04/02 15:03:46.951800,  3] smbd/process.c:1294(switch_message)
>  switch message SMBnttrans (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.951820,  3] smbd/nttrans.c:1786(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: file = ., info_wanted = 0x4
>[2010/04/02 15:03:46.951839, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=.
>[2010/04/02 15:03:46.951855, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file .
>[2010/04/02 15:03:46.951871, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file .
>[2010/04/02 15:03:46.951900, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 4294967295 for file .
>[2010/04/02 15:03:46.951921, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.951938, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.951960, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.952016, 10] smbd/posix_acls.c:2618(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
>[2010/04/02 15:03:46.952074, 10] smbd/posix_acls.c:841(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-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.952206, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.952238, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.952256, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.952277,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.952299,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.952316,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.952347,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.952380, 10] ./modules/vfs_acl_common.c:289(get_nt_acl_internal)
>  get_nt_acl_internal: get_acl_blob returned NT_STATUS_NOT_FOUND
>[2010/04/02 15:03:46.952399,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0770
>[2010/04/02 15:03:46.952416,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0660
>[2010/04/02 15:03:46.952432, 10] ./modules/vfs_acl_common.c:200(add_directory_inheritable_components)
>  add_directory_inheritable_components: directory ., mode = 0770
>[2010/04/02 15:03:46.952449, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.952466, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.952497, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.952515,  3] smbd/nttrans.c:1820(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: sd_size = 168.
>[2010/04/02 15:03:46.952537,  3] smbd/error.c:80(error_packet_set)
>  error packet at smbd/nttrans.c(240) cmd=160 (SMBnttrans) NT_STATUS_BUFFER_TOO_SMALL
>[2010/04/02 15:03:46.952909,  3] smbd/process.c:1485(process_smb)
>  Transaction 294 of length 88 (0 toread)
>[2010/04/02 15:03:46.952931,  3] smbd/process.c:1294(switch_message)
>  switch message SMBnttrans (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.952950,  3] smbd/nttrans.c:1786(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: file = ., info_wanted = 0x4
>[2010/04/02 15:03:46.952969, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=.
>[2010/04/02 15:03:46.952985, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file .
>[2010/04/02 15:03:46.953016, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file .
>[2010/04/02 15:03:46.953045, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 4294967295 for file .
>[2010/04/02 15:03:46.953066, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.953083, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.953111, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.953168, 10] smbd/posix_acls.c:2618(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
>[2010/04/02 15:03:46.953213, 10] smbd/posix_acls.c:841(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-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.953338, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.953377, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.953395, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.953417,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.953438,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.953455,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.953486,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.953512, 10] ./modules/vfs_acl_common.c:289(get_nt_acl_internal)
>  get_nt_acl_internal: get_acl_blob returned NT_STATUS_NOT_FOUND
>[2010/04/02 15:03:46.953530,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0770
>[2010/04/02 15:03:46.953547,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0660
>[2010/04/02 15:03:46.953563, 10] ./modules/vfs_acl_common.c:200(add_directory_inheritable_components)
>  add_directory_inheritable_components: directory ., mode = 0770
>[2010/04/02 15:03:46.953581, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.953597, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.953613, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.953630,  3] smbd/nttrans.c:1820(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: sd_size = 168.
>[2010/04/02 15:03:46.954001,  3] smbd/process.c:1485(process_smb)
>  Transaction 295 of length 45 (0 toread)
>[2010/04/02 15:03:46.954023,  3] smbd/process.c:1294(switch_message)
>  switch message SMBclose (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.954042,  3] smbd/reply.c:4624(reply_close)
>  close directory fnum=7681
>[2010/04/02 15:03:46.954642,  3] smbd/process.c:1485(process_smb)
>  Transaction 296 of length 88 (0 toread)
>[2010/04/02 15:03:46.954665,  3] smbd/process.c:1294(switch_message)
>  switch message SMBnttrans (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.954749,  3] smbd/nttrans.c:1786(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: file = file-with-acls.txt, info_wanted = 0x7
>[2010/04/02 15:03:46.954768, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=file-with-acls.txt
>[2010/04/02 15:03:46.954785, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.954801, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.954832, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 25 for file file-with-acls.txt
>[2010/04/02 15:03:46.954850, 10] smbd/posix_acls.c:564(create_pai_val_v2)
>  create_pai_val_v2: sd_type = 0x8004 num_entries = 3, num_def_entries = 0
>[2010/04/02 15:03:46.954867, 10] smbd/posix_acls.c:417(get_pai_owner_type)
>  get_pai_owner_type: world ace
>[2010/04/02 15:03:46.954896, 10] smbd/posix_acls.c:408(get_pai_owner_type)
>  get_pai_owner_type: uid = 6683
>[2010/04/02 15:03:46.954913, 10] smbd/posix_acls.c:413(get_pai_owner_type)
>  get_pai_owner_type: gid = 100
>[2010/04/02 15:03:46.954929, 10] smbd/posix_acls.c:732(load_inherited_info)
>  load_inherited_info: ACL type 0x8004 for file file-with-acls.txt
>[2010/04/02 15:03:46.954948, 10] smbd/posix_acls.c:158(get_entry_val)
>  get_entry_val: uid = 6683
>[2010/04/02 15:03:46.954964, 10] smbd/posix_acls.c:138(get_pai_entry_val)
>  get_pai_entry_val: uid = 6683
>[2010/04/02 15:03:46.954994, 10] smbd/posix_acls.c:161(get_entry_val)
>  get_entry_val: gid = 100
>[2010/04/02 15:03:46.955034, 10] smbd/posix_acls.c:141(get_pai_entry_val)
>  get_pai_entry_val: gid = 100
>[2010/04/02 15:03:46.955051, 10] smbd/posix_acls.c:165(get_entry_val)
>  get_entry_val: world ace
>[2010/04/02 15:03:46.955067, 10] smbd/posix_acls.c:145(get_pai_entry_val)
>  get_pai_entry_val: world ace
>[2010/04/02 15:03:46.955117, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.955135, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.955158, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.955217, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.955665, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.956221, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.956256, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.956274, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.956297,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.956319,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.956350,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.956391,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.956450,  3] smbd/nttrans.c:1820(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: sd_size = 176.
>[2010/04/02 15:03:46.956471,  3] smbd/error.c:80(error_packet_set)
>  error packet at smbd/nttrans.c(240) cmd=160 (SMBnttrans) NT_STATUS_BUFFER_TOO_SMALL
>[2010/04/02 15:03:46.956846,  3] smbd/process.c:1485(process_smb)
>  Transaction 297 of length 88 (0 toread)
>[2010/04/02 15:03:46.956869,  3] smbd/process.c:1294(switch_message)
>  switch message SMBnttrans (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.956888,  3] smbd/nttrans.c:1786(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: file = file-with-acls.txt, info_wanted = 0x7
>[2010/04/02 15:03:46.956907, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=file-with-acls.txt
>[2010/04/02 15:03:46.956936, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.956955, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.956985, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 25 for file file-with-acls.txt
>[2010/04/02 15:03:46.957003, 10] smbd/posix_acls.c:564(create_pai_val_v2)
>  create_pai_val_v2: sd_type = 0x8004 num_entries = 3, num_def_entries = 0
>[2010/04/02 15:03:46.957020, 10] smbd/posix_acls.c:417(get_pai_owner_type)
>  get_pai_owner_type: world ace
>[2010/04/02 15:03:46.957050, 10] smbd/posix_acls.c:408(get_pai_owner_type)
>  get_pai_owner_type: uid = 6683
>[2010/04/02 15:03:46.957079, 10] smbd/posix_acls.c:413(get_pai_owner_type)
>  get_pai_owner_type: gid = 100
>[2010/04/02 15:03:46.957096, 10] smbd/posix_acls.c:732(load_inherited_info)
>  load_inherited_info: ACL type 0x8004 for file file-with-acls.txt
>[2010/04/02 15:03:46.957115, 10] smbd/posix_acls.c:158(get_entry_val)
>  get_entry_val: uid = 6683
>[2010/04/02 15:03:46.957131, 10] smbd/posix_acls.c:138(get_pai_entry_val)
>  get_pai_entry_val: uid = 6683
>[2010/04/02 15:03:46.957148, 10] smbd/posix_acls.c:161(get_entry_val)
>  get_entry_val: gid = 100
>[2010/04/02 15:03:46.957178, 10] smbd/posix_acls.c:141(get_pai_entry_val)
>  get_pai_entry_val: gid = 100
>[2010/04/02 15:03:46.957195, 10] smbd/posix_acls.c:165(get_entry_val)
>  get_entry_val: world ace
>[2010/04/02 15:03:46.957210, 10] smbd/posix_acls.c:145(get_pai_entry_val)
>  get_pai_entry_val: world ace
>[2010/04/02 15:03:46.957239, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.957256, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.957278, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.957353, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.957841, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.958382, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.958403, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.958420, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.958455,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.958478,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.958495,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.958529,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.958585,  3] smbd/nttrans.c:1820(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: sd_size = 176.
>[2010/04/02 15:03:46.958918,  3] smbd/process.c:1485(process_smb)
>  Transaction 298 of length 76 (0 toread)
>[2010/04/02 15:03:46.958943,  3] smbd/process.c:1294(switch_message)
>  switch message SMBtrans2 (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.958964,  3] smbd/trans2.c:4996(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1004
>[2010/04/02 15:03:46.959007,  3] smbd/trans2.c:5209(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo file-with-acls.txt (fnum = 7679) level=1004 call=7 total_data=0
>[2010/04/02 15:03:46.959044,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.959452,  3] smbd/process.c:1485(process_smb)
>  Transaction 299 of length 45 (0 toread)
>[2010/04/02 15:03:46.959475,  3] smbd/process.c:1294(switch_message)
>  switch message SMBclose (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.959495,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.959532,  3] smbd/reply.c:4634(reply_close)
>  close fd=-1 fnum=7679 (numopen=1)
>[2010/04/02 15:03:46.959570,  2] smbd/close.c:656(close_normal_file)
>  leerling1 closed file file-with-acls.txt (numopen=0) NT_STATUS_OK
>[2010/04/02 15:03:46.960010,  3] smbd/process.c:1485(process_smb)
>  Transaction 300 of length 128 (0 toread)
>[2010/04/02 15:03:46.960046,  3] smbd/process.c:1294(switch_message)
>  switch message SMBntcreateX (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.960075,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [file-with-acls.txt] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.960101, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [file-with-acls.txt] -> [/home/vol1/shares/aclshare/file-with-acls.txt]
>[2010/04/02 15:03:46.960119,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: file-with-acls.txt reduced to /home/vol1/shares/aclshare/file-with-acls.txt
>[2010/04/02 15:03:46.960153,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [file-with-acls.txt] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.960182, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [file-with-acls.txt] -> [/home/vol1/shares/aclshare/file-with-acls.txt]
>[2010/04/02 15:03:46.960200,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: file-with-acls.txt reduced to /home/vol1/shares/aclshare/file-with-acls.txt
>[2010/04/02 15:03:46.960234,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(file-with-acls.txt) returning 0660
>[2010/04/02 15:03:46.960254,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [file-with-acls.txt] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.960275, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [file-with-acls.txt] -> [/home/vol1/shares/aclshare/file-with-acls.txt]
>[2010/04/02 15:03:46.960292,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: file-with-acls.txt reduced to /home/vol1/shares/aclshare/file-with-acls.txt
>[2010/04/02 15:03:46.960317, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=file-with-acls.txt
>[2010/04/02 15:03:46.960335, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.960369, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 25 for file file-with-acls.txt
>[2010/04/02 15:03:46.960401, 10] smbd/posix_acls.c:564(create_pai_val_v2)
>  create_pai_val_v2: sd_type = 0x8004 num_entries = 3, num_def_entries = 0
>[2010/04/02 15:03:46.960420, 10] smbd/posix_acls.c:417(get_pai_owner_type)
>  get_pai_owner_type: world ace
>[2010/04/02 15:03:46.960436, 10] smbd/posix_acls.c:408(get_pai_owner_type)
>  get_pai_owner_type: uid = 6683
>[2010/04/02 15:03:46.960452, 10] smbd/posix_acls.c:413(get_pai_owner_type)
>  get_pai_owner_type: gid = 100
>[2010/04/02 15:03:46.960483, 10] smbd/posix_acls.c:732(load_inherited_info)
>  load_inherited_info: ACL type 0x8004 for file file-with-acls.txt
>[2010/04/02 15:03:46.960503, 10] smbd/posix_acls.c:158(get_entry_val)
>  get_entry_val: uid = 6683
>[2010/04/02 15:03:46.960519, 10] smbd/posix_acls.c:138(get_pai_entry_val)
>  get_pai_entry_val: uid = 6683
>[2010/04/02 15:03:46.960536, 10] smbd/posix_acls.c:161(get_entry_val)
>  get_entry_val: gid = 100
>[2010/04/02 15:03:46.960567, 10] smbd/posix_acls.c:141(get_pai_entry_val)
>  get_pai_entry_val: gid = 100
>[2010/04/02 15:03:46.960583, 10] smbd/posix_acls.c:165(get_entry_val)
>  get_entry_val: world ace
>[2010/04/02 15:03:46.960599, 10] smbd/posix_acls.c:145(get_pai_entry_val)
>  get_pai_entry_val: world ace
>[2010/04/02 15:03:46.960615, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.960631, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.960667, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.960738, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.961157, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.961641, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.961676, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.961746, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.961794,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.961815,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.961832,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.961881,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.961933,  2] smbd/open.c:631(open_file)
>  leerling1 opened file file-with-acls.txt read=No write=No (numopen=1)
>[2010/04/02 15:03:46.961978, 10] smbd/file_access.c:185(can_access_file_data)
>  can_access_file_data: requesting 0x2 on file file-with-acls.txt
>[2010/04/02 15:03:46.962404,  3] smbd/process.c:1485(process_smb)
>  Transaction 301 of length 88 (0 toread)
>[2010/04/02 15:03:46.962427,  3] smbd/process.c:1294(switch_message)
>  switch message SMBnttrans (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.962446,  3] smbd/nttrans.c:1786(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: file = file-with-acls.txt, info_wanted = 0x3
>[2010/04/02 15:03:46.962465, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=file-with-acls.txt
>[2010/04/02 15:03:46.962482, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.962518, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.962549, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 25 for file file-with-acls.txt
>[2010/04/02 15:03:46.962632, 10] smbd/posix_acls.c:564(create_pai_val_v2)
>  create_pai_val_v2: sd_type = 0x8004 num_entries = 3, num_def_entries = 0
>[2010/04/02 15:03:46.962651, 10] smbd/posix_acls.c:417(get_pai_owner_type)
>  get_pai_owner_type: world ace
>[2010/04/02 15:03:46.962667, 10] smbd/posix_acls.c:408(get_pai_owner_type)
>  get_pai_owner_type: uid = 6683
>[2010/04/02 15:03:46.962683, 10] smbd/posix_acls.c:413(get_pai_owner_type)
>  get_pai_owner_type: gid = 100
>[2010/04/02 15:03:46.962699, 10] smbd/posix_acls.c:732(load_inherited_info)
>  load_inherited_info: ACL type 0x8004 for file file-with-acls.txt
>[2010/04/02 15:03:46.962718, 10] smbd/posix_acls.c:158(get_entry_val)
>  get_entry_val: uid = 6683
>[2010/04/02 15:03:46.962743, 10] smbd/posix_acls.c:138(get_pai_entry_val)
>  get_pai_entry_val: uid = 6683
>[2010/04/02 15:03:46.962760, 10] smbd/posix_acls.c:161(get_entry_val)
>  get_entry_val: gid = 100
>[2010/04/02 15:03:46.962776, 10] smbd/posix_acls.c:141(get_pai_entry_val)
>  get_pai_entry_val: gid = 100
>[2010/04/02 15:03:46.962793, 10] smbd/posix_acls.c:165(get_entry_val)
>  get_entry_val: world ace
>[2010/04/02 15:03:46.962809, 10] smbd/posix_acls.c:145(get_pai_entry_val)
>  get_pai_entry_val: world ace
>[2010/04/02 15:03:46.962836, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.962853, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.962876, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.962955, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.963338, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.963799, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.963834, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.963851, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.963889,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.963911,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.963928,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.963965,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.964013,  3] smbd/nttrans.c:1820(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: sd_size = 76.
>[2010/04/02 15:03:46.964033,  3] smbd/error.c:80(error_packet_set)
>  error packet at smbd/nttrans.c(240) cmd=160 (SMBnttrans) NT_STATUS_BUFFER_TOO_SMALL
>[2010/04/02 15:03:46.964351,  3] smbd/process.c:1485(process_smb)
>  Transaction 302 of length 88 (0 toread)
>[2010/04/02 15:03:46.964374,  3] smbd/process.c:1294(switch_message)
>  switch message SMBnttrans (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.964393,  3] smbd/nttrans.c:1786(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: file = file-with-acls.txt, info_wanted = 0x3
>[2010/04/02 15:03:46.964412, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=file-with-acls.txt
>[2010/04/02 15:03:46.964429, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.964445, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file file-with-acls.txt
>[2010/04/02 15:03:46.964474, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 25 for file file-with-acls.txt
>[2010/04/02 15:03:46.964492, 10] smbd/posix_acls.c:564(create_pai_val_v2)
>  create_pai_val_v2: sd_type = 0x8004 num_entries = 3, num_def_entries = 0
>[2010/04/02 15:03:46.964509, 10] smbd/posix_acls.c:417(get_pai_owner_type)
>  get_pai_owner_type: world ace
>[2010/04/02 15:03:46.964541, 10] smbd/posix_acls.c:408(get_pai_owner_type)
>  get_pai_owner_type: uid = 6683
>[2010/04/02 15:03:46.964557, 10] smbd/posix_acls.c:413(get_pai_owner_type)
>  get_pai_owner_type: gid = 100
>[2010/04/02 15:03:46.964573, 10] smbd/posix_acls.c:732(load_inherited_info)
>  load_inherited_info: ACL type 0x8004 for file file-with-acls.txt
>[2010/04/02 15:03:46.964592, 10] smbd/posix_acls.c:158(get_entry_val)
>  get_entry_val: uid = 6683
>[2010/04/02 15:03:46.964640, 10] smbd/posix_acls.c:138(get_pai_entry_val)
>  get_pai_entry_val: uid = 6683
>[2010/04/02 15:03:46.964671, 10] smbd/posix_acls.c:161(get_entry_val)
>  get_entry_val: gid = 100
>[2010/04/02 15:03:46.964739, 10] smbd/posix_acls.c:141(get_pai_entry_val)
>  get_pai_entry_val: gid = 100
>[2010/04/02 15:03:46.964757, 10] smbd/posix_acls.c:165(get_entry_val)
>  get_entry_val: world ace
>[2010/04/02 15:03:46.964773, 10] smbd/posix_acls.c:145(get_pai_entry_val)
>  get_pai_entry_val: world ace
>[2010/04/02 15:03:46.964789, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.964806, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.964828, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.964886, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 2. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.965318, 10] smbd/posix_acls.c:841(print_canon_ace_list)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-14366 uid 6683 (leerling1) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.965785, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.965805, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.965843, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.965866,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.965907,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.965925,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.965972,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.966016,  3] smbd/nttrans.c:1820(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: sd_size = 76.
>[2010/04/02 15:03:46.966458,  3] smbd/process.c:1485(process_smb)
>  Transaction 303 of length 45 (0 toread)
>[2010/04/02 15:03:46.966481,  3] smbd/process.c:1294(switch_message)
>  switch message SMBclose (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.966499,  3] smbd/reply.c:4634(reply_close)
>  close fd=-1 fnum=7682 (numopen=1)
>[2010/04/02 15:03:46.966545,  2] smbd/close.c:656(close_normal_file)
>  leerling1 closed file file-with-acls.txt (numopen=0) NT_STATUS_OK
>[2010/04/02 15:03:46.966959,  3] smbd/process.c:1485(process_smb)
>  Transaction 304 of length 92 (0 toread)
>[2010/04/02 15:03:46.966983,  3] smbd/process.c:1294(switch_message)
>  switch message SMBntcreateX (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.967029,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [.] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.967051, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.967068,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: . reduced to /home/vol1/shares/aclshare
>[2010/04/02 15:03:46.967086,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [.] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.967170, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.967204,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: . reduced to /home/vol1/shares/aclshare
>[2010/04/02 15:03:46.967227,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0660
>[2010/04/02 15:03:46.967260,  3] smbd/vfs.c:851(check_reduced_name)
>  check_reduced_name [.] [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.967280, 10] smbd/vfs.c:938(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/vol1/shares/aclshare]
>[2010/04/02 15:03:46.967297,  3] smbd/vfs.c:1008(check_reduced_name)
>  check_reduced_name: . reduced to /home/vol1/shares/aclshare
>[2010/04/02 15:03:46.967322, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=.
>[2010/04/02 15:03:46.967340, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file .
>[2010/04/02 15:03:46.967383, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 4294967295 for file .
>[2010/04/02 15:03:46.967410, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.967428, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.967450, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.967508, 10] smbd/posix_acls.c:2618(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
>[2010/04/02 15:03:46.967569, 10] smbd/posix_acls.c:841(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-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.967698, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.967716, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.967733, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.967755,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.967790,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.967808,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.967846,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.967866, 10] ./modules/vfs_acl_common.c:289(get_nt_acl_internal)
>  get_nt_acl_internal: get_acl_blob returned NT_STATUS_NOT_FOUND
>[2010/04/02 15:03:46.967889,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0770
>[2010/04/02 15:03:46.967907,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0660
>[2010/04/02 15:03:46.967923, 10] ./modules/vfs_acl_common.c:200(add_directory_inheritable_components)
>  add_directory_inheritable_components: directory ., mode = 0770
>[2010/04/02 15:03:46.967940, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.967962, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.967979, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.968004, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=.
>[2010/04/02 15:03:46.968021, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file .
>[2010/04/02 15:03:46.968061, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 4294967295 for file .
>[2010/04/02 15:03:46.968088, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.968105, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.968127, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.968201, 10] smbd/posix_acls.c:2618(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
>[2010/04/02 15:03:46.968248, 10] smbd/posix_acls.c:841(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-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.968444, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.968463, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.968480, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.968504,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.968524,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.968540,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.968587,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.968661, 10] ./modules/vfs_acl_common.c:289(get_nt_acl_internal)
>  get_nt_acl_internal: get_acl_blob returned NT_STATUS_NOT_FOUND
>[2010/04/02 15:03:46.968692,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0770
>[2010/04/02 15:03:46.968710,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0660
>[2010/04/02 15:03:46.968727, 10] ./modules/vfs_acl_common.c:200(add_directory_inheritable_components)
>  add_directory_inheritable_components: directory ., mode = 0770
>[2010/04/02 15:03:46.968750, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.968767, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.968784, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.969215,  3] smbd/process.c:1485(process_smb)
>  Transaction 305 of length 88 (0 toread)
>[2010/04/02 15:03:46.969238,  3] smbd/process.c:1294(switch_message)
>  switch message SMBnttrans (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.969258,  3] smbd/nttrans.c:1786(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: file = ., info_wanted = 0x4
>[2010/04/02 15:03:46.969277, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=.
>[2010/04/02 15:03:46.969293, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file .
>[2010/04/02 15:03:46.969310, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file .
>[2010/04/02 15:03:46.969346, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 4294967295 for file .
>[2010/04/02 15:03:46.969367, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.969394, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.969418, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.969506, 10] smbd/posix_acls.c:2618(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
>[2010/04/02 15:03:46.969567, 10] smbd/posix_acls.c:841(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-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.969674, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.969692, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.969709, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.969730,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.969752,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.969769,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.969800,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.969832, 10] ./modules/vfs_acl_common.c:289(get_nt_acl_internal)
>  get_nt_acl_internal: get_acl_blob returned NT_STATUS_NOT_FOUND
>[2010/04/02 15:03:46.969851,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0770
>[2010/04/02 15:03:46.969881,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0660
>[2010/04/02 15:03:46.969898, 10] ./modules/vfs_acl_common.c:200(add_directory_inheritable_components)
>  add_directory_inheritable_components: directory ., mode = 0770
>[2010/04/02 15:03:46.969915, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.969945, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.969961, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.969978,  3] smbd/nttrans.c:1820(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: sd_size = 168.
>[2010/04/02 15:03:46.969996,  3] smbd/error.c:80(error_packet_set)
>  error packet at smbd/nttrans.c(240) cmd=160 (SMBnttrans) NT_STATUS_BUFFER_TOO_SMALL
>[2010/04/02 15:03:46.970374,  3] smbd/process.c:1485(process_smb)
>  Transaction 306 of length 88 (0 toread)
>[2010/04/02 15:03:46.970397,  3] smbd/process.c:1294(switch_message)
>  switch message SMBnttrans (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.970416,  3] smbd/nttrans.c:1786(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: file = ., info_wanted = 0x4
>[2010/04/02 15:03:46.970435, 10] ./modules/vfs_acl_common.c:262(get_nt_acl_internal)
>  get_nt_acl_internal: name=.
>[2010/04/02 15:03:46.970466, 10] smbd/posix_acls.c:3443(posix_fget_nt_acl)
>  posix_fget_nt_acl: called for file .
>[2010/04/02 15:03:46.970483, 10] smbd/posix_acls.c:3477(posix_get_nt_acl)
>  posix_get_nt_acl: called for file .
>[2010/04/02 15:03:46.970512, 10] smbd/posix_acls.c:712(load_inherited_info)
>  load_inherited_info: ret = 4294967295 for file .
>[2010/04/02 15:03:46.970533, 10] smbd/posix_acls.c:2605(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/04/02 15:03:46.970560, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.970583, 10] smbd/posix_acls.c:2618(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>[2010/04/02 15:03:46.970647, 10] smbd/posix_acls.c:2618(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
>[2010/04/02 15:03:46.970711, 10] smbd/posix_acls.c:841(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-5-21-2680263629-3535859742-3145682158-100 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/04/02 15:03:46.970829, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.970863, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.970881, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.970902,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(6683, 100) : sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.970929,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.970946,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/04/02 15:03:46.970978,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (6683, 100) - sec_ctx_stack_ndx = 0
>[2010/04/02 15:03:46.970996, 10] ./modules/vfs_acl_common.c:289(get_nt_acl_internal)
>  get_nt_acl_internal: get_acl_blob returned NT_STATUS_NOT_FOUND
>[2010/04/02 15:03:46.971015,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0770
>[2010/04/02 15:03:46.971032,  3] smbd/dosmode.c:166(unix_mode)
>  unix_mode(.) returning 0660
>[2010/04/02 15:03:46.971048, 10] ./modules/vfs_acl_common.c:200(add_directory_inheritable_components)
>  add_directory_inheritable_components: directory ., mode = 0770
>[2010/04/02 15:03:46.971065, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.971094, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/04/02 15:03:46.971112, 10] smbd/posix_acls.c:1117(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/04/02 15:03:46.971129,  3] smbd/nttrans.c:1820(call_nt_transact_query_security_desc)
>  call_nt_transact_query_security_desc: sd_size = 168.
>[2010/04/02 15:03:46.971730,  3] smbd/process.c:1485(process_smb)
>  Transaction 307 of length 45 (0 toread)
>[2010/04/02 15:03:46.971754,  3] smbd/process.c:1294(switch_message)
>  switch message SMBclose (pid 27822) conn 0xb8e03230
>[2010/04/02 15:03:46.971773,  3] smbd/reply.c:4624(reply_close)
>  close directory fnum=7684
>[2010/04/02 15:03:47.074128,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0

Actions: View

Attachments on bug 6917: 5588 | 5589 | 5590