Attachment 4797 Details for Bug 6769 – Log: Level 10, Samba 3.3.8, target exists symlink unlink

Log: Level 10, Samba 3.3.8, target exists symlink unlink

samba-3.3.8-patch-4792-delete-not-hanging-log10.txt (text/plain), 9.42 KB, created by David Sainty on 2009-10-06 22:41:02 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: David Sainty

Created: 2009-10-06 22:41:02 UTC

Size: 9.42 KB

patch

obsolete

>[2009/10/07 16:21:26, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
>  got smb length of 88
>[2009/10/07 16:21:26,  6] smbd/process.c:process_smb(1573)
>  got message type 0x0 of len 0x58
>[2009/10/07 16:21:26,  3] smbd/process.c:process_smb(1576)
>  Transaction 261 of length 92 (0 toread)
>[2009/10/07 16:21:26,  5] lib/util.c:show_msg(645)
>[2009/10/07 16:21:26,  5] lib/util.c:show_msg(655)
>  size=88
>  smb_com=0x6
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=49153
>  smb_tid=1
>  smb_pid=21529
>  smb_uid=100
>  smb_mid=48469
>  smt_wct=1
>  smb_vwv[ 0]=    7 (0x7)
>  smb_bcc=51
>[2009/10/07 16:21:26, 10] lib/util.c:dump_data(2233)
>  [000] 04 2F 00 74 00 65 00 73  00 74 00 64 00 69 00 72  ./.t.e.s .t.d.i.r
>  [010] 00 2F 00 73 00 79 00 6D  00 6C 00 69 00 6E 00 6B  ./.s.y.m .l.i.n.k
>  [020] 00 74 00 6F 00 64 00 65  00 6C 00 65 00 74 00 65  .t.o.d.e .l.e.t.e
>  [030] 00 00 00                                          ... 
>[2009/10/07 16:21:26,  3] smbd/process.c:switch_message(1393)
>  switch message SMBunlink (pid 19910) conn 0x806e9b48
>[2009/10/07 16:21:26,  4] smbd/uid.c:change_to_user(258)
>  change_to_user: Skipping user change - already user
>[2009/10/07 16:21:26,  3] smbd/reply.c:reply_unlink(2633)
>  reply_unlink : testdir/symlinktodelete
>[2009/10/07 16:21:26,  5] smbd/filename.c:unix_convert(155)
>  unix_convert called on file "testdir/symlinktodelete"
>[2009/10/07 16:21:26,  5] smbd/filename.c:unix_convert(304)
>  conversion finished testdir/symlinktodelete -> testdir/symlinktodelete
>[2009/10/07 16:21:26, 10] smbd/reply.c:do_unlink(2306)
>  do_unlink: testdir/symlinktodelete, dirtype = 7
>[2009/10/07 16:21:26,  8] smbd/dosmode.c:dos_mode(361)
>  dos_mode: testdir/symlinktodelete
>[2009/10/07 16:21:26,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
>  dos_mode_from_sbuf returning a
>[2009/10/07 16:21:26,  8] smbd/dosmode.c:dos_mode(403)
>  dos_mode returning a[sparse]
>[2009/10/07 16:21:26, 10] smbd/open.c:create_file_unixpath(2889)
>  create_file_unixpath: access_mask = 0x10000 file_attributes = 0x80, share_access = 0x0, create_disposition = 0x1 create_options = 0x40 oplock_request = 0x0 ea_list = 0x(nil), sd = 0x(nil), fname = testdir/symlinktodelete
>[2009/10/07 16:21:26,  5] smbd/files.c:file_new(121)
>  allocated file structure 1015, fnum = 5111 (7 used)
>[2009/10/07 16:21:26,  3] smbd/dosmode.c:unix_mode(124)
>  unix_mode(testdir/symlinktodelete) returning 0744
>[2009/10/07 16:21:26, 10] smbd/open.c:open_file_ntcreate_internal(1447)
>  open_file_ntcreate: fname=testdir/symlinktodelete, dos_attrs=0x80 access_mask=0x10000 share_access=0x0 create_disposition = 0x1 create_options=0x40 unix mode=0744 oplock_request=0
>[2009/10/07 16:21:26,  8] smbd/dosmode.c:dos_mode(361)
>  dos_mode: testdir/symlinktodelete
>[2009/10/07 16:21:26,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
>  dos_mode_from_sbuf returning a
>[2009/10/07 16:21:26,  8] smbd/dosmode.c:dos_mode(403)
>  dos_mode returning a[sparse]
>[2009/10/07 16:21:26, 10] smbd/open.c:open_file_ntcreate_internal(1625)
>  open_file_ntcreate: fname=testdir/symlinktodelete, after mapping access_mask=0x10000
>[2009/10/07 16:21:26, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100)
>  Locking key 0408000000000000CF04
>[2009/10/07 16:21:26, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129)
>  Allocated locked data 0x0x806e7e80
>[2009/10/07 16:21:26, 10] smbd/open.c:delay_for_oplocks(952)
>  delay_for_oplocks: oplock type 0x10 on file 
>[2009/10/07 16:21:26, 10] smbd/open.c:delay_for_oplocks(952)
>  delay_for_oplocks: oplock type 0x10 on file 
>[2009/10/07 16:21:26,  4] smbd/open.c:open_file_ntcreate_internal(1884)
>  calling open_file with flags=0x0 flags2=0x0 mode=0744, access_mask = 0x10000, open_access_mask = 0x10000
>[2009/10/07 16:21:26, 10] smbd/posix_acls.c:posix_get_nt_acl(3184)
>  posix_get_nt_acl: called for file testdir/symlinktodelete
>[2009/10/07 16:21:26, 10] smbd/posix_acls.c:canonicalise_acl(2335)
>  canonicalise_acl: Access ace entries before arrange :
>[2009/10/07 16:21:26, 10] smbd/posix_acls.c:canonicalise_acl(2348)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER perms rw-
>[2009/10/07 16:21:26, 10] smbd/posix_acls.c:canonicalise_acl(2348)
>  canon_ace index 1. Type = allow SID = S-1-22-2-500 gid 500 (dualriver) SMB_ACL_GROUP_OBJ perms rw-
>[2009/10/07 16:21:26, 10] smbd/posix_acls.c:canonicalise_acl(2348)
>  canon_ace index 2. Type = allow SID = S-1-5-21-1323285546-2887905080-92902717-3010 uid 1005 (dave) SMB_ACL_USER_OBJ perms rw-
>[2009/10/07 16:21:26, 10] smbd/posix_acls.c:print_canon_ace_list(661)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-1323285546-2887905080-92902717-3010 uid 1005 (dave) SMB_ACL_USER_OBJ perms rw-
>  canon_ace index 1. Type = allow SID = S-1-22-2-500 gid 500 (dualriver) SMB_ACL_GROUP_OBJ perms rw-
>  canon_ace index 2. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER perms rw-
>[2009/10/07 16:21:26, 10] smbd/posix_acls.c:map_canon_ace_perms(936)
>  map_canon_ace_perms: Mapped (UNIX) 180 to (NT) 12019f
>[2009/10/07 16:21:26, 10] smbd/posix_acls.c:map_canon_ace_perms(936)
>  map_canon_ace_perms: Mapped (UNIX) 180 to (NT) 12019f
>[2009/10/07 16:21:26, 10] smbd/posix_acls.c:map_canon_ace_perms(936)
>  map_canon_ace_perms: Mapped (UNIX) 180 to (NT) 12019f
>[2009/10/07 16:21:26, 10] smbd/open.c:check_open_rights(96)
>  check_open_rights: file testdir/symlinktodelete requesting 0x10000 returning 0x10000 (NT_STATUS_OK)
>[2009/10/07 16:21:26,  2] smbd/open.c:open_file(551)
>  dave opened file testdir/symlinktodelete read=No write=No (numopen=7)
>[2009/10/07 16:21:26,  5] smbd/oplock.c:set_file_oplock(126)
>  set_file_oplock: granted oplock on file testdir/symlinktodelete, 804:3004cf/14, tv_sec = 4acc0936, tv_usec = d1cae
>[2009/10/07 16:21:26, 10] locking/locking.c:unparse_share_modes(683)
>  unparse_share_modes: del: 0, owrt: Tue Sep 29 18:57:28 2009 NZDT cwrt: Thu Jan  1 12:00:00 1970 NZST, tok: 0, num: 1
>[2009/10/07 16:21:26, 10] locking/locking.c:print_share_mode_table(485)
>  print_share_mode_table: share_mode_entry[0]:  pid = 19910, share_access = 0x0, private_options = 0x40, access_mask = 0x10080, mid = 0x0, type= 0x10, gen_id = 14, uid = 1005, flags = 0, file_id 804:3004cf
>[2009/10/07 16:21:26, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42)
>  Unlocking key 0408000000000000CF04
>[2009/10/07 16:21:26, 10] smbd/open.c:create_file_unixpath(3209)
>  create_file_unixpath: info=1
>[2009/10/07 16:21:26, 10] locking/locking.c:set_delete_on_close(1357)
>  set_delete_on_close: Adding delete on close flag for fnum = 5111, file testdir/symlinktodelete
>[2009/10/07 16:21:26, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100)
>  Locking key 0408000000000000CF04
>[2009/10/07 16:21:26, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129)
>  Allocated locked data 0x0x806e8c80
>[2009/10/07 16:21:26, 10] locking/locking.c:parse_share_modes(519)
>  parse_share_modes: delete_on_close: 0, owrt: Tue Sep 29 18:57:28 2009 NZDT, cwrt: Thu Jan  1 12:00:00 1970 NZST, tok: 0, num_share_modes: 1
>[2009/10/07 16:21:26, 10] locking/locking.c:parse_share_modes(616)
>  parse_share_modes: share_mode_entry[0]:  pid = 19910, share_access = 0x0, private_options = 0x40, access_mask = 0x10080, mid = 0x0, type= 0x10, gen_id = 14, uid = 1005, flags = 0, file_id 804:3004cf
>[2009/10/07 16:21:26, 10] locking/locking.c:unparse_share_modes(683)
>  unparse_share_modes: del: 1, owrt: Tue Sep 29 18:57:28 2009 NZDT cwrt: Thu Jan  1 12:00:00 1970 NZST, tok: 24, num: 1
>[2009/10/07 16:21:26, 10] locking/locking.c:print_share_mode_table(485)
>  print_share_mode_table: share_mode_entry[0]:  pid = 19910, share_access = 0x0, private_options = 0x40, access_mask = 0x10080, mid = 0x0, type= 0x10, gen_id = 14, uid = 1005, flags = 0, file_id 804:3004cf
>[2009/10/07 16:21:26, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42)
>  Unlocking key 0408000000000000CF04
>[2009/10/07 16:21:26, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100)
>  Locking key 0408000000000000CF04
>[2009/10/07 16:21:26, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129)
>  Allocated locked data 0x0x806e9240
>[2009/10/07 16:21:26, 10] locking/locking.c:parse_share_modes(519)
>  parse_share_modes: delete_on_close: 1, owrt: Tue Sep 29 18:57:28 2009 NZDT, cwrt: Thu Jan  1 12:00:00 1970 NZST, tok: 24, num_share_modes: 1
>[2009/10/07 16:21:26, 10] locking/locking.c:parse_share_modes(616)
>  parse_share_modes: share_mode_entry[0]:  pid = 19910, share_access = 0x0, private_options = 0x40, access_mask = 0x10080, mid = 0x0, type= 0x10, gen_id = 14, uid = 1005, flags = 0, file_id 804:3004cf
>[2009/10/07 16:21:26,  5] smbd/close.c:close_remove_share_mode(330)
>  close_remove_share_mode: file testdir/symlinktodelete. Delete on close was set - deleting file.
>[2009/10/07 16:21:26,  5] smbd/close.c:close_remove_share_mode(383)
>  close_remove_share_mode: file testdir/symlinktodelete. Delete on close was set and dev and/or inode does not match
>[2009/10/07 16:21:26,  5] smbd/close.c:close_remove_share_mode(388)
>  close_remove_share_mode: file testdir/symlinktodelete. stored file_id 804:3004cf, stat file_id 804:13c8134
>[2009/10/07 16:21:26, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42)
>  Unlocking key 0408000000000000CF04
>[2009/10/07 16:21:26,  2] smbd/close.c:close_normal_file(606)
>  dave closed file testdir/symlinktodelete (numopen=6) NT_STATUS_OK
>[2009/10/07 16:21:26,  5] smbd/files.c:file_free(471)
>  freed files structure 5111 (6 used)
>[2009/10/07 16:21:26,  5] lib/util.c:show_msg(645)
>[2009/10/07 16:21:26,  5] lib/util.c:show_msg(655)
>  size=35
>  smb_com=0x6
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49153
>  smb_tid=1
>  smb_pid=21529
>  smb_uid=100
>  smb_mid=48469
>  smt_wct=0
>  smb_bcc=0

Actions: View

Attachments on bug 6769: 4770 | 4771 | 4772 | 4792 | 4793 | 4796 | 4797 | 4801 | 4802 | 4811 | 4822 | 4823 | 4824 | 4862