[2010/03/11 09:07:16, 5] smbd/uid.c:368(change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 112 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x70 [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 100 of length 116 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=112 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=100 smt_wct=15 smb_vwv[ 0]= 44 (0x2C) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 2 (0x2) smb_vwv[ 3]=16644 (0x4104) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 44 (0x2C) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=47 [2010/03/11 09:07:16, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 07 01 00 00 00 00 5C 00 2E 00 5F 00 2E ........ .\..._.. [0010] 00 54 00 65 00 6D 00 70 00 6F 00 72 00 61 00 72 .T.e.m.p .o.r.a.r [0020] 00 79 00 49 00 74 00 65 00 6D 00 73 00 00 00 .y.I.t.e .m.s... [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (500, 500) - sec_ctx_stack_ndx = 0 [2010/03/11 09:07:16, 5] auth/token_util.c:528(debug_nt_user_token) NT user token of user S-1-5-21-3902612433-4140503104-1544586071-2000 contains 14 SIDs SID[ 0]: S-1-5-21-3902612433-4140503104-1544586071-2000 SID[ 1]: S-1-22-2-500 SID[ 2]: S-1-1-0 SID[ 3]: S-1-5-2 SID[ 4]: S-1-5-11 SID[ 5]: S-1-22-2-100 SID[ 6]: S-1-22-2-48 SID[ 7]: S-1-22-2-502 SID[ 8]: S-1-22-2-507 SID[ 9]: S-1-22-2-624 SID[ 10]: S-1-22-2-1438 SID[ 11]: S-1-22-2-1693 SID[ 12]: S-1-22-2-1694 SID[ 13]: S-1-22-1-500 SE_PRIV 0x0 0x0 0x0 0x0 [2010/03/11 09:07:16, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 500 Primary group is 500 and contains 9 supplementary groups Group[ 0]: 500 Group[ 1]: 100 Group[ 2]: 48 Group[ 3]: 502 Group[ 4]: 507 Group[ 5]: 624 Group[ 6]: 1438 Group[ 7]: 1693 Group[ 8]: 1694 [2010/03/11 09:07:16, 5] smbd/uid.c:353(change_to_user) change_to_user uid=(0,500) gid=(0,500) [2010/03/11 09:07:16, 3] smbd/trans2.c:3956(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263 [2010/03/11 09:07:16, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "._.TemporaryItems" [2010/03/11 09:07:16, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [._.TEMPORARYITEMS] [2010/03/11 09:07:16, 5] smbd/filename.c:303(unix_convert) unix_convert begin: name = ._.TemporaryItems, dirpath = , start = ._.TemporaryItems [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._.TemporaryItems ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._.TemporaryItems (len 17) ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._.TemporaryItems ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._.TemporaryItems (len 17) ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._.TemporaryItems ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._.TemporaryItems (len 17) ? [2010/03/11 09:07:16, 5] smbd/filename.c:564(unix_convert) New file ._.TemporaryItems [2010/03/11 09:07:16, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [._.TemporaryItems] [/home/jonb] [2010/03/11 09:07:16, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [._.TemporaryItems] -> [/home/jonb/._.TemporaryItems] [2010/03/11 09:07:16, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: ._.TemporaryItems reduced to /home/jonb/._.TemporaryItems [2010/03/11 09:07:16, 3] smbd/trans2.c:4050(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_VFS_STAT of ._.TemporaryItems failed (No such file or directory) [2010/03/11 09:07:16, 3] smbd/error.c:153(reply_unix_error) unix_error_packet: error string = No such file or directory [2010/03/11 09:07:16, 3] smbd/error.c:60(error_packet_set) error packet at smbd/trans2.c(4051) cmd=50 (SMBtrans2) NT_STATUS_OBJECT_NAME_NOT_FOUND [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=35 smb_com=0x32 smb_rcls=52 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=100 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 108 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x6c [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 101 of length 112 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=108 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=101 smt_wct=15 smb_vwv[ 0]= 40 (0x28) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 2 (0x2) smb_vwv[ 3]=16644 (0x4104) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 40 (0x28) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=43 [2010/03/11 09:07:16, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 07 01 00 00 00 00 5C 00 2E 00 5F 00 2E ........ .\..._.. [0010] 00 72 00 74 00 5F 00 73 00 65 00 73 00 73 00 69 .r.t._.s .e.s.s.i [0020] 00 6F 00 6E 00 73 00 7E 00 00 00 .o.n.s.~ ... [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 3] smbd/trans2.c:3956(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263 [2010/03/11 09:07:16, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "._.rt_sessions~" [2010/03/11 09:07:16, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [._.RT_SESSIONS~] [2010/03/11 09:07:16, 5] smbd/filename.c:303(unix_convert) unix_convert begin: name = ._.rt_sessions~, dirpath = , start = ._.rt_sessions~ [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._.rt_sessions~ ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._.rt_sessions~ (len 15) ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._.rt_sessions~ ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._.rt_sessions~ (len 15) ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._.rt_sessions~ ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._.rt_sessions~ (len 15) ? [2010/03/11 09:07:16, 5] smbd/filename.c:564(unix_convert) New file ._.rt_sessions~ [2010/03/11 09:07:16, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [._.rt_sessions~] [/home/jonb] [2010/03/11 09:07:16, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [._.rt_sessions~] -> [/home/jonb/._.rt_sessions~] [2010/03/11 09:07:16, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: ._.rt_sessions~ reduced to /home/jonb/._.rt_sessions~ [2010/03/11 09:07:16, 3] smbd/trans2.c:4050(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_VFS_STAT of ._.rt_sessions~ failed (No such file or directory) [2010/03/11 09:07:16, 3] smbd/error.c:153(reply_unix_error) unix_error_packet: error string = No such file or directory [2010/03/11 09:07:16, 3] smbd/error.c:60(error_packet_set) error packet at smbd/trans2.c(4051) cmd=50 (SMBtrans2) NT_STATUS_OBJECT_NAME_NOT_FOUND [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=35 smb_com=0x32 smb_rcls=52 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=101 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 98 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x62 [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 102 of length 102 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=98 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=102 smt_wct=15 smb_vwv[ 0]= 30 (0x1E) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 2 (0x2) smb_vwv[ 3]=16644 (0x4104) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 30 (0x1E) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=33 [2010/03/11 09:07:16, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 07 01 00 00 00 00 5C 00 2E 00 5F 00 6D ........ .\..._.m [0010] 00 6F 00 6F 00 74 00 6F 00 6F 00 6C 00 73 00 00 .o.o.t.o .o.l.s.. [0020] 00 . [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 3] smbd/trans2.c:3956(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263 [2010/03/11 09:07:16, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "._mootools" [2010/03/11 09:07:16, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [._MOOTOOLS] [2010/03/11 09:07:16, 5] smbd/filename.c:303(unix_convert) unix_convert begin: name = ._mootools, dirpath = , start = ._mootools [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._mootools ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._mootools (len 10) ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._mootools ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._mootools (len 10) ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._mootools ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._mootools (len 10) ? [2010/03/11 09:07:16, 5] smbd/filename.c:564(unix_convert) New file ._mootools [2010/03/11 09:07:16, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [._mootools] [/home/jonb] [2010/03/11 09:07:16, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [._mootools] -> [/home/jonb/._mootools] [2010/03/11 09:07:16, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: ._mootools reduced to /home/jonb/._mootools [2010/03/11 09:07:16, 3] smbd/trans2.c:4050(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_VFS_STAT of ._mootools failed (No such file or directory) [2010/03/11 09:07:16, 3] smbd/error.c:153(reply_unix_error) unix_error_packet: error string = No such file or directory [2010/03/11 09:07:16, 3] smbd/error.c:60(error_packet_set) error packet at smbd/trans2.c(4051) cmd=50 (SMBtrans2) NT_STATUS_OBJECT_NAME_NOT_FOUND [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=35 smb_com=0x32 smb_rcls=52 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=102 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 90 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x5a [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 103 of length 94 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=90 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=103 smt_wct=15 smb_vwv[ 0]= 22 (0x16) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 2 (0x2) smb_vwv[ 3]=16644 (0x4104) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 22 (0x16) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=25 [2010/03/11 09:07:16, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 07 01 00 00 00 00 5C 00 2E 00 5F 00 2E ........ .\..._.. [0010] 00 67 00 65 00 6D 00 00 00 .g.e.m.. . [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 3] smbd/trans2.c:3956(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263 [2010/03/11 09:07:16, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "._.gem" [2010/03/11 09:07:16, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [._.GEM] [2010/03/11 09:07:16, 5] smbd/filename.c:303(unix_convert) unix_convert begin: name = ._.gem, dirpath = , start = ._.gem [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._.gem ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._.gem (len 6) ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._.gem ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._.gem (len 6) ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._.gem ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._.gem (len 6) ? [2010/03/11 09:07:16, 5] smbd/filename.c:564(unix_convert) New file ._.gem [2010/03/11 09:07:16, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [._.gem] [/home/jonb] [2010/03/11 09:07:16, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [._.gem] -> [/home/jonb/._.gem] [2010/03/11 09:07:16, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: ._.gem reduced to /home/jonb/._.gem [2010/03/11 09:07:16, 3] smbd/trans2.c:4050(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_VFS_STAT of ._.gem failed (No such file or directory) [2010/03/11 09:07:16, 3] smbd/error.c:153(reply_unix_error) unix_error_packet: error string = No such file or directory [2010/03/11 09:07:16, 3] smbd/error.c:60(error_packet_set) error packet at smbd/trans2.c(4051) cmd=50 (SMBtrans2) NT_STATUS_OBJECT_NAME_NOT_FOUND [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=35 smb_com=0x32 smb_rcls=52 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=103 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 120 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x78 [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 104 of length 124 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=120 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=104 smt_wct=15 smb_vwv[ 0]= 52 (0x34) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 2 (0x2) smb_vwv[ 3]=16644 (0x4104) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 52 (0x34) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=55 [2010/03/11 09:07:16, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 07 01 00 00 00 00 5C 00 2E 00 5F 00 63 ........ .\..._.c [0010] 00 68 00 75 00 72 00 63 00 68 00 5F 00 74 00 72 .h.u.r.c .h._.t.r [0020] 00 69 00 67 00 67 00 65 00 72 00 73 00 2E 00 73 .i.g.g.e .r.s...s [0030] 00 71 00 6C 00 00 00 .q.l... [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 3] smbd/trans2.c:3956(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263 [2010/03/11 09:07:16, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "._church_triggers.sql" [2010/03/11 09:07:16, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [._CHURCH_TRIGGERS.SQL] [2010/03/11 09:07:16, 5] smbd/filename.c:303(unix_convert) unix_convert begin: name = ._church_triggers.sql, dirpath = , start = ._church_triggers.sql [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._church_triggers.sql ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._church_triggers.sql (len 21) ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._church_triggers.sql ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._church_triggers.sql (len 21) ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._church_triggers.sql ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._church_triggers.sql (len 21) ? [2010/03/11 09:07:16, 5] smbd/filename.c:564(unix_convert) New file ._church_triggers.sql [2010/03/11 09:07:16, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [._church_triggers.sql] [/home/jonb] [2010/03/11 09:07:16, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [._church_triggers.sql] -> [/home/jonb/._church_triggers.sql] [2010/03/11 09:07:16, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: ._church_triggers.sql reduced to /home/jonb/._church_triggers.sql [2010/03/11 09:07:16, 3] smbd/trans2.c:4050(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_VFS_STAT of ._church_triggers.sql failed (No such file or directory) [2010/03/11 09:07:16, 3] smbd/error.c:153(reply_unix_error) unix_error_packet: error string = No such file or directory [2010/03/11 09:07:16, 3] smbd/error.c:60(error_packet_set) error packet at smbd/trans2.c(4051) cmd=50 (SMBtrans2) NT_STATUS_OBJECT_NAME_NOT_FOUND [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=35 smb_com=0x32 smb_rcls=52 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=104 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 94 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x5e [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 105 of length 98 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=94 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=105 smt_wct=15 smb_vwv[ 0]= 26 (0x1A) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 2 (0x2) smb_vwv[ 3]=16644 (0x4104) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 26 (0x1A) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=29 [2010/03/11 09:07:16, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 07 01 00 00 00 00 5C 00 2E 00 5F 00 70 ........ .\..._.p [0010] 00 6F 00 76 00 72 00 61 00 79 00 00 00 .o.v.r.a .y... [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 3] smbd/trans2.c:3956(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263 [2010/03/11 09:07:16, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "._povray" [2010/03/11 09:07:16, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [._POVRAY] [2010/03/11 09:07:16, 5] smbd/filename.c:303(unix_convert) unix_convert begin: name = ._povray, dirpath = , start = ._povray [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._povray ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._povray (len 8) ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._povray ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._povray (len 8) ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._povray ? [2010/03/11 09:07:16, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._povray (len 8) ? [2010/03/11 09:07:16, 5] smbd/filename.c:564(unix_convert) New file ._povray [2010/03/11 09:07:16, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [._povray] [/home/jonb] [2010/03/11 09:07:16, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [._povray] -> [/home/jonb/._povray] [2010/03/11 09:07:16, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: ._povray reduced to /home/jonb/._povray [2010/03/11 09:07:16, 3] smbd/trans2.c:4050(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_VFS_STAT of ._povray failed (No such file or directory) [2010/03/11 09:07:16, 3] smbd/error.c:153(reply_unix_error) unix_error_packet: error string = No such file or directory [2010/03/11 09:07:16, 3] smbd/error.c:60(error_packet_set) error packet at smbd/trans2.c(4051) cmd=50 (SMBtrans2) NT_STATUS_OBJECT_NAME_NOT_FOUND [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=35 smb_com=0x32 smb_rcls=52 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=105 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 106 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x6a [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 106 of length 110 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=106 smb_com=0xa2 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=106 smt_wct=24 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 5120 (0x1400) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 256 (0x100) smb_vwv[ 8]= 512 (0x200) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]=32768 (0x8000) smb_vwv[14]= 0 (0x0) smb_vwv[15]= 1792 (0x700) smb_vwv[16]= 0 (0x0) smb_vwv[17]= 256 (0x100) smb_vwv[18]= 0 (0x0) smb_vwv[19]= 0 (0x0) smb_vwv[20]= 0 (0x0) smb_vwv[21]= 512 (0x200) smb_vwv[22]= 0 (0x0) smb_vwv[23]= 0 (0x0) smb_bcc=23 [2010/03/11 09:07:16, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 5C 00 25 00 62 00 61 00 63 00 6B 00 75 00 70 .\.%.b.a .c.k.u.p [0010] 00 25 00 7E 00 00 00 .%.~... [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 10] smbd/nttrans.c:484(reply_ntcreate_and_X) reply_ntcreate_and_X: flags = 0x0, access_mask = 0x20001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 root_dir_fid = 0x0, fname = %backup%~ [2010/03/11 09:07:16, 10] smbd/open.c:3365(create_file_default) create_file: access_mask = 0x20001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 oplock_request = 0x0 root_dir_fid = 0x0, ea_list = 0x(nil), sd = 0x(nil), create_file_flags = 0x1, fname = %backup%~ [2010/03/11 09:07:16, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "%backup%~" [2010/03/11 09:07:16, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [%BACKUP%~] [2010/03/11 09:07:16, 5] smbd/statcache.c:138(stat_cache_add) stat_cache_add: Added entry (7f26626c3c20:size 9) %BACKUP%~ -> %backup%~ [2010/03/11 09:07:16, 5] smbd/filename.c:297(unix_convert) conversion finished %backup%~ -> %backup%~ [2010/03/11 09:07:16, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [%backup%~] [/home/jonb] [2010/03/11 09:07:16, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [%backup%~] -> [/home/jonb/%backup%~] [2010/03/11 09:07:16, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: %backup%~ reduced to /home/jonb/%backup%~ [2010/03/11 09:07:16, 10] smbd/open.c:2896(create_file_unixpath) create_file_unixpath: access_mask = 0x20001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 oplock_request = 0x0 ea_list = 0x(nil), sd = 0x(nil), fname = %backup%~ [2010/03/11 09:07:16, 5] smbd/files.c:103(file_new) allocated file structure 15286, fnum = 19382 (1 used) [2010/03/11 09:07:16, 3] smbd/dosmode.c:149(unix_mode) unix_mode(%backup%~) returning 0744 [2010/03/11 09:07:16, 10] smbd/open.c:1475(open_file_ntcreate) open_file_ntcreate: fname=%backup%~, dos_attrs=0x80 access_mask=0x20001 share_access=0x7 create_disposition = 0x1 create_options=0x0 unix mode=0744 oplock_request=0 [2010/03/11 09:07:16, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [%backup%~] [/home/jonb] [2010/03/11 09:07:16, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [%backup%~] -> [/home/jonb/%backup%~] [2010/03/11 09:07:16, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: %backup%~ reduced to /home/jonb/%backup%~ [2010/03/11 09:07:16, 8] smbd/dosmode.c:494(dos_mode) dos_mode: %backup%~ [2010/03/11 09:07:16, 8] smbd/dosmode.c:189(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/03/11 09:07:16, 8] smbd/dosmode.c:547(dos_mode) dos_mode returning [2010/03/11 09:07:16, 10] smbd/open.c:1653(open_file_ntcreate) open_file_ntcreate: fname=%backup%~, after mapping access_mask=0x20001 [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 00FD0000000000007A8D [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x7f26626c3ed0 [2010/03/11 09:07:16, 10] smbd/open.c:980(delay_for_oplocks) delay_for_oplocks: oplock type 0x10 on file [2010/03/11 09:07:16, 10] smbd/open.c:980(delay_for_oplocks) delay_for_oplocks: oplock type 0x10 on file [2010/03/11 09:07:16, 4] smbd/open.c:1913(open_file_ntcreate) calling open_file with flags=0x0 flags2=0x0 mode=0744, access_mask = 0x20001, open_access_mask = 0x20001 [2010/03/11 09:07:16, 10] smbd/open.c:160(fd_open) fd_open: name %backup%~, flags = 00 mode = 0744, fd = 31. [2010/03/11 09:07:16, 2] smbd/open.c:580(open_file) jonb opened file %backup%~ read=Yes write=No (numopen=1) [2010/03/11 09:07:16, 5] smbd/oplock.c:86(set_file_oplock) set_file_oplock: granted oplock on file %backup%~, fd00:3c18d7a:0/5, tv_sec = 4b990724, tv_usec = 9b7de [2010/03/11 09:07:16, 10] locking/locking.c:716(unparse_share_modes) unparse_share_modes: del: 0, owrt: Mon 01 Mar 2010 03:45:04 PM CST CST cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num: 1 [2010/03/11 09:07:16, 10] locking/locking.c:518(print_share_mode_table) print_share_mode_table: share_mode_entry[0]: pid = 31889, share_access = 0x7, private_options = 0x0, access_mask = 0x20081, mid = 0x0, type= 0x10, gen_id = 5, uid = 500, flags = 0, file_id fd00:3c18d7a:0 [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 00FD0000000000007A8D [2010/03/11 09:07:16, 10] smbd/open.c:3201(create_file_unixpath) create_file_unixpath: info=1 [2010/03/11 09:07:16, 10] smbd/open.c:3485(create_file_default) create_file: info=1 [2010/03/11 09:07:16, 8] smbd/dosmode.c:494(dos_mode) dos_mode: %backup%~ [2010/03/11 09:07:16, 8] smbd/dosmode.c:189(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/03/11 09:07:16, 8] smbd/dosmode.c:547(dos_mode) dos_mode returning [2010/03/11 09:07:16, 5] smbd/nttrans.c:672(reply_ntcreate_and_X) reply_ntcreate_and_X: fnum = 19382, open name = %backup%~ [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 59 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x3b [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 107 of length 63 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=59 smb_com=0x2e smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=107 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=19382 (0x4BB6) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 512 (0x200) smb_vwv[ 6]= 512 (0x200) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 512 (0x200) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 00FD0000000000007A8D [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x7f26626c3be0 [2010/03/11 09:07:16, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id fd00:3c18d7a:0 [2010/03/11 09:07:16, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File %backup%~, offset = 0, count = 512, type = READ [2010/03/11 09:07:16, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 0, count_out = 512 [2010/03/11 09:07:16, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 31 0 512 0 [2010/03/11 09:07:16, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=31 offset=0 count=512 type=0 [2010/03/11 09:07:16, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 31 is returned info 2 pid 0 [2010/03/11 09:07:16, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/03/11 09:07:16, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=0 len=512 unlocked for fnum 19382 file %backup%~ [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 00FD0000000000007A8D [2010/03/11 09:07:16, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=0 len=512 unlocked for fnum 19382 file %backup%~ [2010/03/11 09:07:16, 10] smbd/fileio.c:105(read_file) read_file (%backup%~): pos = 0, size = 512, returned 512 [2010/03/11 09:07:16, 3] smbd/reply.c:3446(send_file_readX) send_file_readX fnum=19382 max=512 nread=512 [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 41 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x29 [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 108 of length 45 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=41 smb_com=0x4 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=108 smt_wct=3 smb_vwv[ 0]=19382 (0x4BB6) smb_vwv[ 1]=65535 (0xFFFF) smb_vwv[ 2]=65535 (0xFFFF) smb_bcc=0 [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 3] smbd/reply.c:4488(reply_close) close fd=31 fnum=19382 (numopen=1) [2010/03/11 09:07:16, 6] smbd/close.c:454(set_close_write_time) close_write_time: Sun Feb 7 00:28:15 2106 [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 00FD0000000000007A8D [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x7f26626c3c10 [2010/03/11 09:07:16, 10] locking/locking.c:552(parse_share_modes) parse_share_modes: delete_on_close: 0, owrt: Mon 01 Mar 2010 03:45:04 PM CST CST, cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num_share_modes: 1 [2010/03/11 09:07:16, 10] locking/locking.c:649(parse_share_modes) parse_share_modes: share_mode_entry[0]: pid = 31889, share_access = 0x7, private_options = 0x0, access_mask = 0x20081, mid = 0x0, type= 0x10, gen_id = 5, uid = 500, flags = 0, file_id fd00:3c18d7a:0 [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 00FD0000000000007A8D [2010/03/11 09:07:16, 10] locking/posix.c:495(get_windows_lock_ref_count) get_windows_lock_count for file %backup%~ = 0 [2010/03/11 09:07:16, 10] locking/posix.c:521(delete_windows_lock_ref_count) delete_windows_lock_ref_count for file %backup%~ [2010/03/11 09:07:16, 2] smbd/close.c:612(close_normal_file) jonb closed file %backup%~ (numopen=0) NT_STATUS_OK [2010/03/11 09:07:16, 5] smbd/files.c:474(file_free) freed files structure 19382 (0 used) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=35 smb_com=0x4 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=108 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 108 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x6c [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 109 of length 112 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=108 smb_com=0xa2 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=109 smt_wct=24 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 5632 (0x1600) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 256 (0x100) smb_vwv[ 8]= 512 (0x200) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]=32768 (0x8000) smb_vwv[14]= 0 (0x0) smb_vwv[15]= 1792 (0x700) smb_vwv[16]= 0 (0x0) smb_vwv[17]= 256 (0x100) smb_vwv[18]= 0 (0x0) smb_vwv[19]= 0 (0x0) smb_vwv[20]= 0 (0x0) smb_vwv[21]= 512 (0x200) smb_vwv[22]= 0 (0x0) smb_vwv[23]= 0 (0x0) smb_bcc=25 [2010/03/11 09:07:16, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 5C 00 73 00 61 00 6D 00 62 00 61 00 5F 00 64 .\.s.a.m .b.a._.d [0010] 00 75 00 6D 00 70 00 00 00 .u.m.p.. . [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 10] smbd/nttrans.c:484(reply_ntcreate_and_X) reply_ntcreate_and_X: flags = 0x0, access_mask = 0x20001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 root_dir_fid = 0x0, fname = samba_dump [2010/03/11 09:07:16, 10] smbd/open.c:3365(create_file_default) create_file: access_mask = 0x20001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 oplock_request = 0x0 root_dir_fid = 0x0, ea_list = 0x(nil), sd = 0x(nil), create_file_flags = 0x1, fname = samba_dump [2010/03/11 09:07:16, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "samba_dump" [2010/03/11 09:07:16, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [SAMBA_DUMP] [2010/03/11 09:07:16, 5] smbd/statcache.c:138(stat_cache_add) stat_cache_add: Added entry (7f26626c3c20:size a) SAMBA_DUMP -> samba_dump [2010/03/11 09:07:16, 5] smbd/filename.c:297(unix_convert) conversion finished samba_dump -> samba_dump [2010/03/11 09:07:16, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [samba_dump] [/home/jonb] [2010/03/11 09:07:16, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [samba_dump] -> [/home/jonb/samba_dump] [2010/03/11 09:07:16, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: samba_dump reduced to /home/jonb/samba_dump [2010/03/11 09:07:16, 10] smbd/open.c:2896(create_file_unixpath) create_file_unixpath: access_mask = 0x20001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 oplock_request = 0x0 ea_list = 0x(nil), sd = 0x(nil), fname = samba_dump [2010/03/11 09:07:16, 5] smbd/files.c:103(file_new) allocated file structure 15287, fnum = 19383 (1 used) [2010/03/11 09:07:16, 3] smbd/dosmode.c:149(unix_mode) unix_mode(samba_dump) returning 0744 [2010/03/11 09:07:16, 10] smbd/open.c:1475(open_file_ntcreate) open_file_ntcreate: fname=samba_dump, dos_attrs=0x80 access_mask=0x20001 share_access=0x7 create_disposition = 0x1 create_options=0x0 unix mode=0744 oplock_request=0 [2010/03/11 09:07:16, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [samba_dump] [/home/jonb] [2010/03/11 09:07:16, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [samba_dump] -> [/home/jonb/samba_dump] [2010/03/11 09:07:16, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: samba_dump reduced to /home/jonb/samba_dump [2010/03/11 09:07:16, 8] smbd/dosmode.c:494(dos_mode) dos_mode: samba_dump [2010/03/11 09:07:16, 8] smbd/dosmode.c:189(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/03/11 09:07:16, 8] smbd/dosmode.c:547(dos_mode) dos_mode returning [2010/03/11 09:07:16, 10] smbd/open.c:1653(open_file_ntcreate) open_file_ntcreate: fname=samba_dump, after mapping access_mask=0x20001 [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 00FD0000000000000E8A [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x7f26626c3ed0 [2010/03/11 09:07:16, 10] smbd/open.c:980(delay_for_oplocks) delay_for_oplocks: oplock type 0x10 on file [2010/03/11 09:07:16, 10] smbd/open.c:980(delay_for_oplocks) delay_for_oplocks: oplock type 0x10 on file [2010/03/11 09:07:16, 4] smbd/open.c:1913(open_file_ntcreate) calling open_file with flags=0x0 flags2=0x0 mode=0744, access_mask = 0x20001, open_access_mask = 0x20001 [2010/03/11 09:07:16, 10] smbd/open.c:160(fd_open) fd_open: name samba_dump, flags = 00 mode = 0744, fd = 31. [2010/03/11 09:07:16, 2] smbd/open.c:580(open_file) jonb opened file samba_dump read=Yes write=No (numopen=1) [2010/03/11 09:07:16, 5] smbd/oplock.c:86(set_file_oplock) set_file_oplock: granted oplock on file samba_dump, fd00:3c18a0e:0/6, tv_sec = 4b990724, tv_usec = 9fdf8 [2010/03/11 09:07:16, 10] locking/locking.c:716(unparse_share_modes) unparse_share_modes: del: 0, owrt: Thu 11 Mar 2010 08:59:10 AM CST CST cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num: 1 [2010/03/11 09:07:16, 10] locking/locking.c:518(print_share_mode_table) print_share_mode_table: share_mode_entry[0]: pid = 31889, share_access = 0x7, private_options = 0x0, access_mask = 0x20081, mid = 0x0, type= 0x10, gen_id = 6, uid = 500, flags = 0, file_id fd00:3c18a0e:0 [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 00FD0000000000000E8A [2010/03/11 09:07:16, 10] smbd/open.c:3201(create_file_unixpath) create_file_unixpath: info=1 [2010/03/11 09:07:16, 10] smbd/open.c:3485(create_file_default) create_file: info=1 [2010/03/11 09:07:16, 8] smbd/dosmode.c:494(dos_mode) dos_mode: samba_dump [2010/03/11 09:07:16, 8] smbd/dosmode.c:189(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/03/11 09:07:16, 8] smbd/dosmode.c:547(dos_mode) dos_mode returning [2010/03/11 09:07:16, 5] smbd/nttrans.c:672(reply_ntcreate_and_X) reply_ntcreate_and_X: fnum = 19383, open name = samba_dump [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 59 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x3b [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 110 of length 63 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=59 smb_com=0x2e smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=110 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=19383 (0x4BB7) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 512 (0x200) smb_vwv[ 6]= 512 (0x200) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 512 (0x200) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 00FD0000000000000E8A [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x7f26626c3be0 [2010/03/11 09:07:16, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id fd00:3c18a0e:0 [2010/03/11 09:07:16, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File samba_dump, offset = 0, count = 512, type = READ [2010/03/11 09:07:16, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 0, count_out = 512 [2010/03/11 09:07:16, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 31 0 512 0 [2010/03/11 09:07:16, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=31 offset=0 count=512 type=0 [2010/03/11 09:07:16, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 31 is returned info 2 pid 0 [2010/03/11 09:07:16, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/03/11 09:07:16, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=0 len=512 unlocked for fnum 19383 file samba_dump [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 00FD0000000000000E8A [2010/03/11 09:07:16, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=0 len=512 unlocked for fnum 19383 file samba_dump [2010/03/11 09:07:16, 10] smbd/fileio.c:105(read_file) read_file (samba_dump): pos = 0, size = 512, returned 512 [2010/03/11 09:07:16, 3] smbd/reply.c:3446(send_file_readX) send_file_readX fnum=19383 max=512 nread=512 [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 41 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x29 [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 111 of length 45 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=41 smb_com=0x4 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=111 smt_wct=3 smb_vwv[ 0]=19383 (0x4BB7) smb_vwv[ 1]=65535 (0xFFFF) smb_vwv[ 2]=65535 (0xFFFF) smb_bcc=0 [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 3] smbd/reply.c:4488(reply_close) close fd=31 fnum=19383 (numopen=1) [2010/03/11 09:07:16, 6] smbd/close.c:454(set_close_write_time) close_write_time: Sun Feb 7 00:28:15 2106 [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 00FD0000000000000E8A [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x7f26626c3c10 [2010/03/11 09:07:16, 10] locking/locking.c:552(parse_share_modes) parse_share_modes: delete_on_close: 0, owrt: Thu 11 Mar 2010 08:59:10 AM CST CST, cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num_share_modes: 1 [2010/03/11 09:07:16, 10] locking/locking.c:649(parse_share_modes) parse_share_modes: share_mode_entry[0]: pid = 31889, share_access = 0x7, private_options = 0x0, access_mask = 0x20081, mid = 0x0, type= 0x10, gen_id = 6, uid = 500, flags = 0, file_id fd00:3c18a0e:0 [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 00FD0000000000000E8A [2010/03/11 09:07:16, 10] locking/posix.c:495(get_windows_lock_ref_count) get_windows_lock_count for file samba_dump = 0 [2010/03/11 09:07:16, 10] locking/posix.c:521(delete_windows_lock_ref_count) delete_windows_lock_ref_count for file samba_dump [2010/03/11 09:07:16, 2] smbd/close.c:612(close_normal_file) jonb closed file samba_dump (numopen=0) NT_STATUS_OK [2010/03/11 09:07:16, 5] smbd/files.c:474(file_free) freed files structure 19383 (0 used) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=35 smb_com=0x4 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=111 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 110 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x6e [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 112 of length 114 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=110 smb_com=0xa2 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=112 smt_wct=24 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 6144 (0x1800) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 256 (0x100) smb_vwv[ 8]= 512 (0x200) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]=32768 (0x8000) smb_vwv[14]= 0 (0x0) smb_vwv[15]= 1792 (0x700) smb_vwv[16]= 0 (0x0) smb_vwv[17]= 256 (0x100) smb_vwv[18]= 0 (0x0) smb_vwv[19]= 0 (0x0) smb_vwv[20]= 0 (0x0) smb_vwv[21]= 512 (0x200) smb_vwv[22]= 0 (0x0) smb_vwv[23]= 0 (0x0) smb_bcc=27 [2010/03/11 09:07:16, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 5C 00 71 00 5F 00 61 00 6E 00 64 00 5F 00 61 .\.q._.a .n.d._.a [0010] 00 2E 00 6D 00 6F 00 76 00 00 00 ...m.o.v ... [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 10] smbd/nttrans.c:484(reply_ntcreate_and_X) reply_ntcreate_and_X: flags = 0x0, access_mask = 0x20001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 root_dir_fid = 0x0, fname = q_and_a.mov [2010/03/11 09:07:16, 10] smbd/open.c:3365(create_file_default) create_file: access_mask = 0x20001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 oplock_request = 0x0 root_dir_fid = 0x0, ea_list = 0x(nil), sd = 0x(nil), create_file_flags = 0x1, fname = q_and_a.mov [2010/03/11 09:07:16, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "q_and_a.mov" [2010/03/11 09:07:16, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [Q_AND_A.MOV] [2010/03/11 09:07:16, 5] smbd/statcache.c:138(stat_cache_add) stat_cache_add: Added entry (7f26626c3c30:size b) Q_AND_A.MOV -> q_and_a.mov [2010/03/11 09:07:16, 5] smbd/filename.c:297(unix_convert) conversion finished q_and_a.mov -> q_and_a.mov [2010/03/11 09:07:16, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [q_and_a.mov] [/home/jonb] [2010/03/11 09:07:16, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [q_and_a.mov] -> [/home/jonb/q_and_a.mov] [2010/03/11 09:07:16, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: q_and_a.mov reduced to /home/jonb/q_and_a.mov [2010/03/11 09:07:16, 10] smbd/open.c:2896(create_file_unixpath) create_file_unixpath: access_mask = 0x20001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 oplock_request = 0x0 ea_list = 0x(nil), sd = 0x(nil), fname = q_and_a.mov [2010/03/11 09:07:16, 5] smbd/files.c:103(file_new) allocated file structure 15288, fnum = 19384 (1 used) [2010/03/11 09:07:16, 3] smbd/dosmode.c:149(unix_mode) unix_mode(q_and_a.mov) returning 0744 [2010/03/11 09:07:16, 10] smbd/open.c:1475(open_file_ntcreate) open_file_ntcreate: fname=q_and_a.mov, dos_attrs=0x80 access_mask=0x20001 share_access=0x7 create_disposition = 0x1 create_options=0x0 unix mode=0744 oplock_request=0 [2010/03/11 09:07:16, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [q_and_a.mov] [/home/jonb] [2010/03/11 09:07:16, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [q_and_a.mov] -> [/home/jonb/q_and_a.mov] [2010/03/11 09:07:16, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: q_and_a.mov reduced to /home/jonb/q_and_a.mov [2010/03/11 09:07:16, 8] smbd/dosmode.c:494(dos_mode) dos_mode: q_and_a.mov [2010/03/11 09:07:16, 8] smbd/dosmode.c:189(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/03/11 09:07:16, 8] smbd/dosmode.c:547(dos_mode) dos_mode returning [2010/03/11 09:07:16, 10] smbd/open.c:1653(open_file_ntcreate) open_file_ntcreate: fname=q_and_a.mov, after mapping access_mask=0x20001 [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 00FD000000000000408C [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x7f26626c3ee0 [2010/03/11 09:07:16, 10] smbd/open.c:980(delay_for_oplocks) delay_for_oplocks: oplock type 0x10 on file [2010/03/11 09:07:16, 10] smbd/open.c:980(delay_for_oplocks) delay_for_oplocks: oplock type 0x10 on file [2010/03/11 09:07:16, 4] smbd/open.c:1913(open_file_ntcreate) calling open_file with flags=0x0 flags2=0x0 mode=0744, access_mask = 0x20001, open_access_mask = 0x20001 [2010/03/11 09:07:16, 10] smbd/open.c:160(fd_open) fd_open: name q_and_a.mov, flags = 00 mode = 0744, fd = 31. [2010/03/11 09:07:16, 2] smbd/open.c:580(open_file) jonb opened file q_and_a.mov read=Yes write=No (numopen=1) [2010/03/11 09:07:16, 5] smbd/oplock.c:86(set_file_oplock) set_file_oplock: granted oplock on file q_and_a.mov, fd00:3c18c40:0/7, tv_sec = 4b990724, tv_usec = d3df9 [2010/03/11 09:07:16, 10] locking/locking.c:716(unparse_share_modes) unparse_share_modes: del: 0, owrt: Fri 04 Dec 2009 04:39:18 PM CST CST cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num: 1 [2010/03/11 09:07:16, 10] locking/locking.c:518(print_share_mode_table) print_share_mode_table: share_mode_entry[0]: pid = 31889, share_access = 0x7, private_options = 0x0, access_mask = 0x20081, mid = 0x0, type= 0x10, gen_id = 7, uid = 500, flags = 0, file_id fd00:3c18c40:0 [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 00FD000000000000408C [2010/03/11 09:07:16, 10] smbd/open.c:3201(create_file_unixpath) create_file_unixpath: info=1 [2010/03/11 09:07:16, 10] smbd/open.c:3485(create_file_default) create_file: info=1 [2010/03/11 09:07:16, 8] smbd/dosmode.c:494(dos_mode) dos_mode: q_and_a.mov [2010/03/11 09:07:16, 8] smbd/dosmode.c:189(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/03/11 09:07:16, 8] smbd/dosmode.c:547(dos_mode) dos_mode returning [2010/03/11 09:07:16, 5] smbd/nttrans.c:672(reply_ntcreate_and_X) reply_ntcreate_and_X: fnum = 19384, open name = q_and_a.mov [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 59 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x3b [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 113 of length 63 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=59 smb_com=0x2e smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=113 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=19384 (0x4BB8) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 8 (0x8) smb_vwv[ 6]= 8 (0x8) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 8 (0x8) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 00FD000000000000408C [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x7f26626c3be0 [2010/03/11 09:07:16, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id fd00:3c18c40:0 [2010/03/11 09:07:16, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File q_and_a.mov, offset = 0, count = 8, type = READ [2010/03/11 09:07:16, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 0, count_out = 8 [2010/03/11 09:07:16, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 31 0 8 0 [2010/03/11 09:07:16, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=31 offset=0 count=8 type=0 [2010/03/11 09:07:16, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 31 is returned info 2 pid 0 [2010/03/11 09:07:16, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/03/11 09:07:16, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=0 len=8 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:16, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 00FD000000000000408C [2010/03/11 09:07:16, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=0 len=8 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:16, 10] smbd/fileio.c:105(read_file) read_file (q_and_a.mov): pos = 0, size = 8, returned 8 [2010/03/11 09:07:16, 3] smbd/reply.c:3446(send_file_readX) send_file_readX fnum=19384 max=8 nread=8 [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 59 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x3b [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 114 of length 63 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=59 smb_com=0x2e smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=114 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=19384 (0x4BB8) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 32 (0x20) smb_vwv[ 6]= 32 (0x20) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 32 (0x20) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id fd00:3c18c40:0 [2010/03/11 09:07:16, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File q_and_a.mov, offset = 0, count = 32, type = READ [2010/03/11 09:07:16, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 0, count_out = 32 [2010/03/11 09:07:16, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 31 0 32 0 [2010/03/11 09:07:16, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=31 offset=0 count=32 type=0 [2010/03/11 09:07:16, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 31 is returned info 2 pid 0 [2010/03/11 09:07:16, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/03/11 09:07:16, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=0 len=32 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:16, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=0 len=32 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:16, 10] smbd/fileio.c:105(read_file) read_file (q_and_a.mov): pos = 0, size = 32, returned 32 [2010/03/11 09:07:16, 3] smbd/reply.c:3446(send_file_readX) send_file_readX fnum=19384 max=32 nread=32 [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 59 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x3b [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 115 of length 63 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=59 smb_com=0x2e smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=115 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=19384 (0x4BB8) smb_vwv[ 3]= 32 (0x20) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 8 (0x8) smb_vwv[ 6]= 8 (0x8) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 8 (0x8) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id fd00:3c18c40:0 [2010/03/11 09:07:16, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File q_and_a.mov, offset = 32, count = 8, type = READ [2010/03/11 09:07:16, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 32, count_out = 8 [2010/03/11 09:07:16, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 31 32 8 0 [2010/03/11 09:07:16, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=31 offset=32 count=8 type=0 [2010/03/11 09:07:16, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 31 is returned info 2 pid 0 [2010/03/11 09:07:16, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/03/11 09:07:16, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=32 len=8 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:16, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=32 len=8 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:16, 10] smbd/fileio.c:105(read_file) read_file (q_and_a.mov): pos = 32, size = 8, returned 8 [2010/03/11 09:07:16, 3] smbd/reply.c:3446(send_file_readX) send_file_readX fnum=19384 max=8 nread=8 [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 59 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x3b [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 116 of length 63 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=59 smb_com=0x2e smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=116 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=19384 (0x4BB8) smb_vwv[ 3]= 40 (0x28) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 8 (0x8) smb_vwv[ 6]= 8 (0x8) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 8 (0x8) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id fd00:3c18c40:0 [2010/03/11 09:07:16, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File q_and_a.mov, offset = 40, count = 8, type = READ [2010/03/11 09:07:16, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 40, count_out = 8 [2010/03/11 09:07:16, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 31 40 8 0 [2010/03/11 09:07:16, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=31 offset=40 count=8 type=0 [2010/03/11 09:07:16, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 31 is returned info 2 pid 0 [2010/03/11 09:07:16, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/03/11 09:07:16, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=40 len=8 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:16, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=40 len=8 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:16, 10] smbd/fileio.c:105(read_file) read_file (q_and_a.mov): pos = 40, size = 8, returned 8 [2010/03/11 09:07:16, 3] smbd/reply.c:3446(send_file_readX) send_file_readX fnum=19384 max=8 nread=8 [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 59 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x3b [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 117 of length 63 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=59 smb_com=0x2e smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=117 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=19384 (0x4BB8) smb_vwv[ 3]= 4301 (0x10CD) smb_vwv[ 4]= 2952 (0xB88) smb_vwv[ 5]= 8 (0x8) smb_vwv[ 6]= 8 (0x8) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 8 (0x8) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id fd00:3c18c40:0 [2010/03/11 09:07:16, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File q_and_a.mov, offset = 193466573, count = 8, type = READ [2010/03/11 09:07:16, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 193466573, count_out = 8 [2010/03/11 09:07:16, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 31 193466573 8 0 [2010/03/11 09:07:16, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=31 offset=193466573 count=8 type=0 [2010/03/11 09:07:16, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 31 is returned info 2 pid 0 [2010/03/11 09:07:16, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/03/11 09:07:16, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=193466573 len=8 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:16, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=193466573 len=8 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:16, 10] smbd/fileio.c:105(read_file) read_file (q_and_a.mov): pos = 193466573, size = 8, returned 8 [2010/03/11 09:07:16, 3] smbd/reply.c:3446(send_file_readX) send_file_readX fnum=19384 max=8 nread=8 [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 59 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x3b [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 118 of length 63 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=59 smb_com=0x2e smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=118 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=19384 (0x4BB8) smb_vwv[ 3]=32602 (0x7F5A) smb_vwv[ 4]= 2954 (0xB8A) smb_vwv[ 5]= 8 (0x8) smb_vwv[ 6]= 8 (0x8) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 8 (0x8) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id fd00:3c18c40:0 [2010/03/11 09:07:16, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File q_and_a.mov, offset = 193625946, count = 8, type = READ [2010/03/11 09:07:16, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 193625946, count_out = 8 [2010/03/11 09:07:16, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 31 193625946 8 0 [2010/03/11 09:07:16, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=31 offset=193625946 count=8 type=0 [2010/03/11 09:07:16, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 31 is returned info 2 pid 0 [2010/03/11 09:07:16, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/03/11 09:07:16, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=193625946 len=8 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:16, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=193625946 len=8 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:16, 10] smbd/fileio.c:105(read_file) read_file (q_and_a.mov): pos = 193625946, size = 8, returned 8 [2010/03/11 09:07:16, 3] smbd/reply.c:3446(send_file_readX) send_file_readX fnum=19384 max=8 nread=8 [2010/03/11 09:07:16, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 59 [2010/03/11 09:07:16, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x3b [2010/03/11 09:07:16, 3] smbd/process.c:1459(process_smb) Transaction 119 of length 63 (0 toread) [2010/03/11 09:07:16, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:16, 5] lib/util.c:642(show_msg) size=59 smb_com=0x2e smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=119 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=19384 (0x4BB8) smb_vwv[ 3]=32610 (0x7F62) smb_vwv[ 4]= 2954 (0xB8A) smb_vwv[ 5]=61440 (0xF000) smb_vwv[ 6]=61440 (0xF000) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]=61440 (0xF000) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:16, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:16, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:16, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id fd00:3c18c40:0 [2010/03/11 09:07:16, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File q_and_a.mov, offset = 193625954, count = 61440, type = READ [2010/03/11 09:07:16, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 193625954, count_out = 61440 [2010/03/11 09:07:16, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 31 193625954 61440 0 [2010/03/11 09:07:16, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=31 offset=193625954 count=61440 type=0 [2010/03/11 09:07:16, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 31 is returned info 2 pid 0 [2010/03/11 09:07:16, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/03/11 09:07:16, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=193625954 len=61440 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:16, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=193625954 len=61440 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:16, 10] smbd/fileio.c:105(read_file) read_file (q_and_a.mov): pos = 193625954, size = 61440, returned 61440 [2010/03/11 09:07:16, 3] smbd/reply.c:3446(send_file_readX) send_file_readX fnum=19384 max=61440 nread=61440 [2010/03/11 09:07:17, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 59 [2010/03/11 09:07:17, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x3b [2010/03/11 09:07:17, 3] smbd/process.c:1459(process_smb) Transaction 120 of length 63 (0 toread) [2010/03/11 09:07:17, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:17, 5] lib/util.c:642(show_msg) size=59 smb_com=0x2e smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=120 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=19384 (0x4BB8) smb_vwv[ 3]=28514 (0x6F62) smb_vwv[ 4]= 2955 (0xB8B) smb_vwv[ 5]=61440 (0xF000) smb_vwv[ 6]=61440 (0xF000) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]=61440 (0xF000) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:17, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:17, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:17, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id fd00:3c18c40:0 [2010/03/11 09:07:17, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File q_and_a.mov, offset = 193687394, count = 61440, type = READ [2010/03/11 09:07:17, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 193687394, count_out = 61440 [2010/03/11 09:07:17, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 31 193687394 61440 0 [2010/03/11 09:07:17, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=31 offset=193687394 count=61440 type=0 [2010/03/11 09:07:17, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 31 is returned info 2 pid 0 [2010/03/11 09:07:17, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/03/11 09:07:17, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=193687394 len=61440 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:17, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=193687394 len=61440 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:17, 10] smbd/fileio.c:105(read_file) read_file (q_and_a.mov): pos = 193687394, size = 61440, returned 61440 [2010/03/11 09:07:17, 3] smbd/reply.c:3446(send_file_readX) send_file_readX fnum=19384 max=61440 nread=61440 [2010/03/11 09:07:17, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 59 [2010/03/11 09:07:17, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x3b [2010/03/11 09:07:17, 3] smbd/process.c:1459(process_smb) Transaction 121 of length 63 (0 toread) [2010/03/11 09:07:17, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:17, 5] lib/util.c:642(show_msg) size=59 smb_com=0x2e smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=121 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=19384 (0x4BB8) smb_vwv[ 3]=24418 (0x5F62) smb_vwv[ 4]= 2956 (0xB8C) smb_vwv[ 5]=36527 (0x8EAF) smb_vwv[ 6]=36527 (0x8EAF) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]=36527 (0x8EAF) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:17, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:17, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:17, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id fd00:3c18c40:0 [2010/03/11 09:07:17, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File q_and_a.mov, offset = 193748834, count = 36527, type = READ [2010/03/11 09:07:17, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 193748834, count_out = 36527 [2010/03/11 09:07:17, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 31 193748834 36527 0 [2010/03/11 09:07:17, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=31 offset=193748834 count=36527 type=0 [2010/03/11 09:07:17, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 31 is returned info 2 pid 0 [2010/03/11 09:07:17, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/03/11 09:07:17, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=193748834 len=36527 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:17, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=193748834 len=36527 unlocked for fnum 19384 file q_and_a.mov [2010/03/11 09:07:17, 10] smbd/fileio.c:105(read_file) read_file (q_and_a.mov): pos = 193748834, size = 36527, returned 36527 [2010/03/11 09:07:17, 3] smbd/reply.c:3446(send_file_readX) send_file_readX fnum=19384 max=36527 nread=36527 [2010/03/11 09:07:17, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 41 [2010/03/11 09:07:17, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x29 [2010/03/11 09:07:17, 3] smbd/process.c:1459(process_smb) Transaction 122 of length 45 (0 toread) [2010/03/11 09:07:17, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:17, 5] lib/util.c:642(show_msg) size=41 smb_com=0x4 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=122 smt_wct=3 smb_vwv[ 0]=19384 (0x4BB8) smb_vwv[ 1]=65535 (0xFFFF) smb_vwv[ 2]=65535 (0xFFFF) smb_bcc=0 [2010/03/11 09:07:17, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:17, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:17, 3] smbd/reply.c:4488(reply_close) close fd=31 fnum=19384 (numopen=1) [2010/03/11 09:07:17, 6] smbd/close.c:454(set_close_write_time) close_write_time: Sun Feb 7 00:28:15 2106 [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 00FD000000000000408C [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x7f26626c3c10 [2010/03/11 09:07:17, 10] locking/locking.c:552(parse_share_modes) parse_share_modes: delete_on_close: 0, owrt: Fri 04 Dec 2009 04:39:18 PM CST CST, cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num_share_modes: 1 [2010/03/11 09:07:17, 10] locking/locking.c:649(parse_share_modes) parse_share_modes: share_mode_entry[0]: pid = 31889, share_access = 0x7, private_options = 0x0, access_mask = 0x20081, mid = 0x0, type= 0x10, gen_id = 7, uid = 500, flags = 0, file_id fd00:3c18c40:0 [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 00FD000000000000408C [2010/03/11 09:07:17, 10] locking/posix.c:495(get_windows_lock_ref_count) get_windows_lock_count for file q_and_a.mov = 0 [2010/03/11 09:07:17, 10] locking/posix.c:521(delete_windows_lock_ref_count) delete_windows_lock_ref_count for file q_and_a.mov [2010/03/11 09:07:17, 2] smbd/close.c:612(close_normal_file) jonb closed file q_and_a.mov (numopen=0) NT_STATUS_OK [2010/03/11 09:07:17, 5] smbd/files.c:474(file_free) freed files structure 19384 (0 used) [2010/03/11 09:07:17, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:17, 5] lib/util.c:642(show_msg) size=35 smb_com=0x4 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=122 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:17, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 110 [2010/03/11 09:07:17, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x6e [2010/03/11 09:07:17, 3] smbd/process.c:1459(process_smb) Transaction 123 of length 114 (0 toread) [2010/03/11 09:07:17, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:17, 5] lib/util.c:642(show_msg) size=110 smb_com=0xa2 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=123 smt_wct=24 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 6144 (0x1800) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 256 (0x100) smb_vwv[ 8]= 512 (0x200) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]=32768 (0x8000) smb_vwv[14]= 0 (0x0) smb_vwv[15]= 1792 (0x700) smb_vwv[16]= 0 (0x0) smb_vwv[17]= 256 (0x100) smb_vwv[18]= 0 (0x0) smb_vwv[19]= 0 (0x0) smb_vwv[20]= 0 (0x0) smb_vwv[21]= 512 (0x200) smb_vwv[22]= 0 (0x0) smb_vwv[23]= 0 (0x0) smb_bcc=27 [2010/03/11 09:07:17, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 5C 00 71 00 5F 00 61 00 6E 00 64 00 5F 00 61 .\.q._.a .n.d._.a [0010] 00 2E 00 6D 00 6F 00 76 00 00 00 ...m.o.v ... [2010/03/11 09:07:17, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:17, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:17, 10] smbd/nttrans.c:484(reply_ntcreate_and_X) reply_ntcreate_and_X: flags = 0x0, access_mask = 0x20001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 root_dir_fid = 0x0, fname = q_and_a.mov [2010/03/11 09:07:17, 10] smbd/open.c:3365(create_file_default) create_file: access_mask = 0x20001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 oplock_request = 0x0 root_dir_fid = 0x0, ea_list = 0x(nil), sd = 0x(nil), create_file_flags = 0x1, fname = q_and_a.mov [2010/03/11 09:07:17, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "q_and_a.mov" [2010/03/11 09:07:17, 10] smbd/statcache.c:274(stat_cache_lookup) stat_cache_lookup: lookup succeeded for name [Q_AND_A.MOV] -> [q_and_a.mov] [2010/03/11 09:07:17, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [q_and_a.mov] [/home/jonb] [2010/03/11 09:07:17, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [q_and_a.mov] -> [/home/jonb/q_and_a.mov] [2010/03/11 09:07:17, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: q_and_a.mov reduced to /home/jonb/q_and_a.mov [2010/03/11 09:07:17, 10] smbd/open.c:2896(create_file_unixpath) create_file_unixpath: access_mask = 0x20001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 oplock_request = 0x0 ea_list = 0x(nil), sd = 0x(nil), fname = q_and_a.mov [2010/03/11 09:07:17, 5] smbd/files.c:103(file_new) allocated file structure 15289, fnum = 19385 (1 used) [2010/03/11 09:07:17, 3] smbd/dosmode.c:149(unix_mode) unix_mode(q_and_a.mov) returning 0744 [2010/03/11 09:07:17, 10] smbd/open.c:1475(open_file_ntcreate) open_file_ntcreate: fname=q_and_a.mov, dos_attrs=0x80 access_mask=0x20001 share_access=0x7 create_disposition = 0x1 create_options=0x0 unix mode=0744 oplock_request=0 [2010/03/11 09:07:17, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [q_and_a.mov] [/home/jonb] [2010/03/11 09:07:17, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [q_and_a.mov] -> [/home/jonb/q_and_a.mov] [2010/03/11 09:07:17, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: q_and_a.mov reduced to /home/jonb/q_and_a.mov [2010/03/11 09:07:17, 8] smbd/dosmode.c:494(dos_mode) dos_mode: q_and_a.mov [2010/03/11 09:07:17, 8] smbd/dosmode.c:189(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/03/11 09:07:17, 8] smbd/dosmode.c:547(dos_mode) dos_mode returning [2010/03/11 09:07:17, 10] smbd/open.c:1653(open_file_ntcreate) open_file_ntcreate: fname=q_and_a.mov, after mapping access_mask=0x20001 [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 00FD000000000000408C [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x7f26626c3e80 [2010/03/11 09:07:17, 10] smbd/open.c:980(delay_for_oplocks) delay_for_oplocks: oplock type 0x10 on file [2010/03/11 09:07:17, 10] smbd/open.c:980(delay_for_oplocks) delay_for_oplocks: oplock type 0x10 on file [2010/03/11 09:07:17, 4] smbd/open.c:1913(open_file_ntcreate) calling open_file with flags=0x0 flags2=0x0 mode=0744, access_mask = 0x20001, open_access_mask = 0x20001 [2010/03/11 09:07:17, 10] smbd/open.c:160(fd_open) fd_open: name q_and_a.mov, flags = 00 mode = 0744, fd = 31. [2010/03/11 09:07:17, 2] smbd/open.c:580(open_file) jonb opened file q_and_a.mov read=Yes write=No (numopen=1) [2010/03/11 09:07:17, 5] smbd/oplock.c:86(set_file_oplock) set_file_oplock: granted oplock on file q_and_a.mov, fd00:3c18c40:0/8, tv_sec = 4b990725, tv_usec = 1feba [2010/03/11 09:07:17, 10] locking/locking.c:716(unparse_share_modes) unparse_share_modes: del: 0, owrt: Fri 04 Dec 2009 04:39:18 PM CST CST cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num: 1 [2010/03/11 09:07:17, 10] locking/locking.c:518(print_share_mode_table) print_share_mode_table: share_mode_entry[0]: pid = 31889, share_access = 0x7, private_options = 0x0, access_mask = 0x20081, mid = 0x0, type= 0x10, gen_id = 8, uid = 500, flags = 0, file_id fd00:3c18c40:0 [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 00FD000000000000408C [2010/03/11 09:07:17, 10] smbd/open.c:3201(create_file_unixpath) create_file_unixpath: info=1 [2010/03/11 09:07:17, 10] smbd/open.c:3485(create_file_default) create_file: info=1 [2010/03/11 09:07:17, 8] smbd/dosmode.c:494(dos_mode) dos_mode: q_and_a.mov [2010/03/11 09:07:17, 8] smbd/dosmode.c:189(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/03/11 09:07:17, 8] smbd/dosmode.c:547(dos_mode) dos_mode returning [2010/03/11 09:07:17, 5] smbd/nttrans.c:672(reply_ntcreate_and_X) reply_ntcreate_and_X: fnum = 19385, open name = q_and_a.mov [2010/03/11 09:07:17, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 59 [2010/03/11 09:07:17, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x3b [2010/03/11 09:07:17, 3] smbd/process.c:1459(process_smb) Transaction 124 of length 63 (0 toread) [2010/03/11 09:07:17, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:17, 5] lib/util.c:642(show_msg) size=59 smb_com=0x2e smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=124 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=19385 (0x4BB9) smb_vwv[ 3]=32768 (0x8000) smb_vwv[ 4]= 184 (0xB8) smb_vwv[ 5]=32768 (0x8000) smb_vwv[ 6]=32768 (0x8000) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]=32768 (0x8000) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:17, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:17, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 00FD000000000000408C [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x7f26626c3be0 [2010/03/11 09:07:17, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id fd00:3c18c40:0 [2010/03/11 09:07:17, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File q_and_a.mov, offset = 12091392, count = 32768, type = READ [2010/03/11 09:07:17, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 12091392, count_out = 32768 [2010/03/11 09:07:17, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 31 12091392 32768 0 [2010/03/11 09:07:17, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=31 offset=12091392 count=32768 type=0 [2010/03/11 09:07:17, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 31 is returned info 2 pid 0 [2010/03/11 09:07:17, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/03/11 09:07:17, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=12091392 len=32768 unlocked for fnum 19385 file q_and_a.mov [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 00FD000000000000408C [2010/03/11 09:07:17, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=12091392 len=32768 unlocked for fnum 19385 file q_and_a.mov [2010/03/11 09:07:17, 10] smbd/fileio.c:105(read_file) read_file (q_and_a.mov): pos = 12091392, size = 32768, returned 32768 [2010/03/11 09:07:17, 3] smbd/reply.c:3446(send_file_readX) send_file_readX fnum=19385 max=32768 nread=32768 [2010/03/11 09:07:17, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 59 [2010/03/11 09:07:17, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x3b [2010/03/11 09:07:17, 3] smbd/process.c:1459(process_smb) Transaction 125 of length 63 (0 toread) [2010/03/11 09:07:17, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:17, 5] lib/util.c:642(show_msg) size=59 smb_com=0x2e smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=125 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=19385 (0x4BB9) smb_vwv[ 3]=32768 (0x8000) smb_vwv[ 4]= 186 (0xBA) smb_vwv[ 5]=32768 (0x8000) smb_vwv[ 6]=32768 (0x8000) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]=32768 (0x8000) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:17, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:17, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:17, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id fd00:3c18c40:0 [2010/03/11 09:07:17, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File q_and_a.mov, offset = 12222464, count = 32768, type = READ [2010/03/11 09:07:17, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 12222464, count_out = 32768 [2010/03/11 09:07:17, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 31 12222464 32768 0 [2010/03/11 09:07:17, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=31 offset=12222464 count=32768 type=0 [2010/03/11 09:07:17, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 31 is returned info 2 pid 0 [2010/03/11 09:07:17, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/03/11 09:07:17, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=12222464 len=32768 unlocked for fnum 19385 file q_and_a.mov [2010/03/11 09:07:17, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=12222464 len=32768 unlocked for fnum 19385 file q_and_a.mov [2010/03/11 09:07:17, 10] smbd/fileio.c:105(read_file) read_file (q_and_a.mov): pos = 12222464, size = 32768, returned 32768 [2010/03/11 09:07:17, 3] smbd/reply.c:3446(send_file_readX) send_file_readX fnum=19385 max=32768 nread=32768 [2010/03/11 09:07:17, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 59 [2010/03/11 09:07:17, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x3b [2010/03/11 09:07:17, 3] smbd/process.c:1459(process_smb) Transaction 126 of length 63 (0 toread) [2010/03/11 09:07:17, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:17, 5] lib/util.c:642(show_msg) size=59 smb_com=0x2e smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=126 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=19385 (0x4BB9) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 185 (0xB9) smb_vwv[ 5]=61440 (0xF000) smb_vwv[ 6]=61440 (0xF000) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]=61440 (0xF000) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:17, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:17, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:17, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id fd00:3c18c40:0 [2010/03/11 09:07:17, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File q_and_a.mov, offset = 12124160, count = 61440, type = READ [2010/03/11 09:07:17, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 12124160, count_out = 61440 [2010/03/11 09:07:17, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 31 12124160 61440 0 [2010/03/11 09:07:17, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=31 offset=12124160 count=61440 type=0 [2010/03/11 09:07:17, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 31 is returned info 2 pid 0 [2010/03/11 09:07:17, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/03/11 09:07:17, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=12124160 len=61440 unlocked for fnum 19385 file q_and_a.mov [2010/03/11 09:07:17, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=12124160 len=61440 unlocked for fnum 19385 file q_and_a.mov [2010/03/11 09:07:17, 10] smbd/fileio.c:105(read_file) read_file (q_and_a.mov): pos = 12124160, size = 61440, returned 61440 [2010/03/11 09:07:17, 3] smbd/reply.c:3446(send_file_readX) send_file_readX fnum=19385 max=61440 nread=61440 [2010/03/11 09:07:17, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 59 [2010/03/11 09:07:17, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x3b [2010/03/11 09:07:17, 3] smbd/process.c:1459(process_smb) Transaction 127 of length 63 (0 toread) [2010/03/11 09:07:17, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:17, 5] lib/util.c:642(show_msg) size=59 smb_com=0x2e smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=127 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=19385 (0x4BB9) smb_vwv[ 3]=61440 (0xF000) smb_vwv[ 4]= 185 (0xB9) smb_vwv[ 5]=36864 (0x9000) smb_vwv[ 6]=36864 (0x9000) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]=36864 (0x9000) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:17, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:17, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:17, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id fd00:3c18c40:0 [2010/03/11 09:07:17, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File q_and_a.mov, offset = 12185600, count = 36864, type = READ [2010/03/11 09:07:17, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 12185600, count_out = 36864 [2010/03/11 09:07:17, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 31 12185600 36864 0 [2010/03/11 09:07:17, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=31 offset=12185600 count=36864 type=0 [2010/03/11 09:07:17, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 31 is returned info 2 pid 0 [2010/03/11 09:07:17, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/03/11 09:07:17, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=12185600 len=36864 unlocked for fnum 19385 file q_and_a.mov [2010/03/11 09:07:17, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=12185600 len=36864 unlocked for fnum 19385 file q_and_a.mov [2010/03/11 09:07:17, 10] smbd/fileio.c:105(read_file) read_file (q_and_a.mov): pos = 12185600, size = 36864, returned 36864 [2010/03/11 09:07:17, 3] smbd/reply.c:3446(send_file_readX) send_file_readX fnum=19385 max=36864 nread=36864 [2010/03/11 09:07:17, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 41 [2010/03/11 09:07:17, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x29 [2010/03/11 09:07:17, 3] smbd/process.c:1459(process_smb) Transaction 128 of length 45 (0 toread) [2010/03/11 09:07:17, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:17, 5] lib/util.c:642(show_msg) size=41 smb_com=0x4 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=128 smt_wct=3 smb_vwv[ 0]=19385 (0x4BB9) smb_vwv[ 1]=65535 (0xFFFF) smb_vwv[ 2]=65535 (0xFFFF) smb_bcc=0 [2010/03/11 09:07:17, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:17, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:17, 3] smbd/reply.c:4488(reply_close) close fd=31 fnum=19385 (numopen=1) [2010/03/11 09:07:17, 6] smbd/close.c:454(set_close_write_time) close_write_time: Sun Feb 7 00:28:15 2106 [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 00FD000000000000408C [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x7f26626c3c10 [2010/03/11 09:07:17, 10] locking/locking.c:552(parse_share_modes) parse_share_modes: delete_on_close: 0, owrt: Fri 04 Dec 2009 04:39:18 PM CST CST, cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num_share_modes: 1 [2010/03/11 09:07:17, 10] locking/locking.c:649(parse_share_modes) parse_share_modes: share_mode_entry[0]: pid = 31889, share_access = 0x7, private_options = 0x0, access_mask = 0x20081, mid = 0x0, type= 0x10, gen_id = 8, uid = 500, flags = 0, file_id fd00:3c18c40:0 [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 00FD000000000000408C [2010/03/11 09:07:17, 10] locking/posix.c:495(get_windows_lock_ref_count) get_windows_lock_count for file q_and_a.mov = 0 [2010/03/11 09:07:17, 10] locking/posix.c:521(delete_windows_lock_ref_count) delete_windows_lock_ref_count for file q_and_a.mov [2010/03/11 09:07:17, 2] smbd/close.c:612(close_normal_file) jonb closed file q_and_a.mov (numopen=0) NT_STATUS_OK [2010/03/11 09:07:17, 5] smbd/files.c:474(file_free) freed files structure 19385 (0 used) [2010/03/11 09:07:17, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:17, 5] lib/util.c:642(show_msg) size=35 smb_com=0x4 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=128 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:17, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 132 [2010/03/11 09:07:17, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x84 [2010/03/11 09:07:17, 3] smbd/process.c:1459(process_smb) Transaction 129 of length 136 (0 toread) [2010/03/11 09:07:17, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:17, 5] lib/util.c:642(show_msg) size=132 smb_com=0xa2 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=129 smt_wct=24 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=11776 (0x2E00) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 256 (0x100) smb_vwv[ 8]= 512 (0x200) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]=32768 (0x8000) smb_vwv[14]= 0 (0x0) smb_vwv[15]= 1792 (0x700) smb_vwv[16]= 0 (0x0) smb_vwv[17]= 256 (0x100) smb_vwv[18]= 0 (0x0) smb_vwv[19]= 0 (0x0) smb_vwv[20]= 0 (0x0) smb_vwv[21]= 512 (0x200) smb_vwv[22]= 0 (0x0) smb_vwv[23]= 0 (0x0) smb_bcc=49 [2010/03/11 09:07:17, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 5C 00 74 00 61 00 62 00 6C 00 65 00 5F 00 74 .\.t.a.b .l.e._.t [0010] 00 65 00 6D 00 70 00 6C 00 61 00 74 00 65 00 2E .e.m.p.l .a.t.e.. [0020] 00 69 00 6E 00 63 00 2E 00 70 00 68 00 70 00 00 .i.n.c.. .p.h.p.. [0030] 00 . [2010/03/11 09:07:17, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:17, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:17, 10] smbd/nttrans.c:484(reply_ntcreate_and_X) reply_ntcreate_and_X: flags = 0x0, access_mask = 0x20001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 root_dir_fid = 0x0, fname = table_template.inc.php [2010/03/11 09:07:17, 10] smbd/open.c:3365(create_file_default) create_file: access_mask = 0x20001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 oplock_request = 0x0 root_dir_fid = 0x0, ea_list = 0x(nil), sd = 0x(nil), create_file_flags = 0x1, fname = table_template.inc.php [2010/03/11 09:07:17, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "table_template.inc.php" [2010/03/11 09:07:17, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [TABLE_TEMPLATE.INC.PHP] [2010/03/11 09:07:17, 5] smbd/statcache.c:138(stat_cache_add) stat_cache_add: Added entry (7f26626c3c90:size 16) TABLE_TEMPLATE.INC.PHP -> table_template.inc.php [2010/03/11 09:07:17, 5] smbd/filename.c:297(unix_convert) conversion finished table_template.inc.php -> table_template.inc.php [2010/03/11 09:07:17, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [table_template.inc.php] [/home/jonb] [2010/03/11 09:07:17, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [table_template.inc.php] -> [/home/jonb/table_template.inc.php] [2010/03/11 09:07:17, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: table_template.inc.php reduced to /home/jonb/table_template.inc.php [2010/03/11 09:07:17, 10] smbd/open.c:2896(create_file_unixpath) create_file_unixpath: access_mask = 0x20001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 oplock_request = 0x0 ea_list = 0x(nil), sd = 0x(nil), fname = table_template.inc.php [2010/03/11 09:07:17, 5] smbd/files.c:103(file_new) allocated file structure 15290, fnum = 19386 (1 used) [2010/03/11 09:07:17, 3] smbd/dosmode.c:149(unix_mode) unix_mode(table_template.inc.php) returning 0744 [2010/03/11 09:07:17, 10] smbd/open.c:1475(open_file_ntcreate) open_file_ntcreate: fname=table_template.inc.php, dos_attrs=0x80 access_mask=0x20001 share_access=0x7 create_disposition = 0x1 create_options=0x0 unix mode=0744 oplock_request=0 [2010/03/11 09:07:17, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [table_template.inc.php] [/home/jonb] [2010/03/11 09:07:17, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [table_template.inc.php] -> [/home/jonb/table_template.inc.php] [2010/03/11 09:07:17, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: table_template.inc.php reduced to /home/jonb/table_template.inc.php [2010/03/11 09:07:17, 8] smbd/dosmode.c:494(dos_mode) dos_mode: table_template.inc.php [2010/03/11 09:07:17, 8] smbd/dosmode.c:189(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/03/11 09:07:17, 8] smbd/dosmode.c:547(dos_mode) dos_mode returning [2010/03/11 09:07:17, 10] smbd/open.c:1653(open_file_ntcreate) open_file_ntcreate: fname=table_template.inc.php, after mapping access_mask=0x20001 [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 00FD0000000000005C8C [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x7f26626c3f70 [2010/03/11 09:07:17, 10] smbd/open.c:980(delay_for_oplocks) delay_for_oplocks: oplock type 0x10 on file [2010/03/11 09:07:17, 10] smbd/open.c:980(delay_for_oplocks) delay_for_oplocks: oplock type 0x10 on file [2010/03/11 09:07:17, 4] smbd/open.c:1913(open_file_ntcreate) calling open_file with flags=0x0 flags2=0x0 mode=0744, access_mask = 0x20001, open_access_mask = 0x20001 [2010/03/11 09:07:17, 10] smbd/open.c:160(fd_open) fd_open: name table_template.inc.php, flags = 00 mode = 0744, fd = 31. [2010/03/11 09:07:17, 2] smbd/open.c:580(open_file) jonb opened file table_template.inc.php read=Yes write=No (numopen=1) [2010/03/11 09:07:17, 5] smbd/oplock.c:86(set_file_oplock) set_file_oplock: granted oplock on file table_template.inc.php, fd00:3c18c5c:0/9, tv_sec = 4b990725, tv_usec = 6031c [2010/03/11 09:07:17, 10] locking/locking.c:716(unparse_share_modes) unparse_share_modes: del: 0, owrt: Tue 19 Jan 2010 07:34:11 PM CST CST cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num: 1 [2010/03/11 09:07:17, 10] locking/locking.c:518(print_share_mode_table) print_share_mode_table: share_mode_entry[0]: pid = 31889, share_access = 0x7, private_options = 0x0, access_mask = 0x20081, mid = 0x0, type= 0x10, gen_id = 9, uid = 500, flags = 0, file_id fd00:3c18c5c:0 [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 00FD0000000000005C8C [2010/03/11 09:07:17, 10] smbd/open.c:3201(create_file_unixpath) create_file_unixpath: info=1 [2010/03/11 09:07:17, 10] smbd/open.c:3485(create_file_default) create_file: info=1 [2010/03/11 09:07:17, 8] smbd/dosmode.c:494(dos_mode) dos_mode: table_template.inc.php [2010/03/11 09:07:17, 8] smbd/dosmode.c:189(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/03/11 09:07:17, 8] smbd/dosmode.c:547(dos_mode) dos_mode returning [2010/03/11 09:07:17, 5] smbd/nttrans.c:672(reply_ntcreate_and_X) reply_ntcreate_and_X: fnum = 19386, open name = table_template.inc.php [2010/03/11 09:07:17, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 59 [2010/03/11 09:07:17, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x3b [2010/03/11 09:07:17, 3] smbd/process.c:1459(process_smb) Transaction 130 of length 63 (0 toread) [2010/03/11 09:07:17, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:17, 5] lib/util.c:642(show_msg) size=59 smb_com=0x2e smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=130 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=19386 (0x4BBA) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 512 (0x200) smb_vwv[ 6]= 512 (0x200) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 512 (0x200) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:17, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:17, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 00FD0000000000005C8C [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x7f26626c3be0 [2010/03/11 09:07:17, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id fd00:3c18c5c:0 [2010/03/11 09:07:17, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File table_template.inc.php, offset = 0, count = 512, type = READ [2010/03/11 09:07:17, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 0, count_out = 512 [2010/03/11 09:07:17, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 31 0 512 0 [2010/03/11 09:07:17, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=31 offset=0 count=512 type=0 [2010/03/11 09:07:17, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 31 is returned info 2 pid 0 [2010/03/11 09:07:17, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/03/11 09:07:17, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=0 len=512 unlocked for fnum 19386 file table_template.inc.php [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 00FD0000000000005C8C [2010/03/11 09:07:17, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=0 len=512 unlocked for fnum 19386 file table_template.inc.php [2010/03/11 09:07:17, 10] smbd/fileio.c:105(read_file) read_file (table_template.inc.php): pos = 0, size = 512, returned 512 [2010/03/11 09:07:17, 3] smbd/reply.c:3446(send_file_readX) send_file_readX fnum=19386 max=512 nread=512 [2010/03/11 09:07:17, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 59 [2010/03/11 09:07:17, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x3b [2010/03/11 09:07:17, 3] smbd/process.c:1459(process_smb) Transaction 131 of length 63 (0 toread) [2010/03/11 09:07:17, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:17, 5] lib/util.c:642(show_msg) size=59 smb_com=0x2e smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=131 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=19386 (0x4BBA) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 1285 (0x505) smb_vwv[ 6]= 1285 (0x505) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 1285 (0x505) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:17, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:17, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:17, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id fd00:3c18c5c:0 [2010/03/11 09:07:17, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File table_template.inc.php, offset = 0, count = 1285, type = READ [2010/03/11 09:07:17, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 0, count_out = 1285 [2010/03/11 09:07:17, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 31 0 1285 0 [2010/03/11 09:07:17, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=31 offset=0 count=1285 type=0 [2010/03/11 09:07:17, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 31 is returned info 2 pid 0 [2010/03/11 09:07:17, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/03/11 09:07:17, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=0 len=1285 unlocked for fnum 19386 file table_template.inc.php [2010/03/11 09:07:17, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=0 len=1285 unlocked for fnum 19386 file table_template.inc.php [2010/03/11 09:07:17, 10] smbd/fileio.c:105(read_file) read_file (table_template.inc.php): pos = 0, size = 1285, returned 1285 [2010/03/11 09:07:17, 3] smbd/reply.c:3446(send_file_readX) send_file_readX fnum=19386 max=1285 nread=1285 [2010/03/11 09:07:17, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 41 [2010/03/11 09:07:17, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x29 [2010/03/11 09:07:17, 3] smbd/process.c:1459(process_smb) Transaction 132 of length 45 (0 toread) [2010/03/11 09:07:17, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:17, 5] lib/util.c:642(show_msg) size=41 smb_com=0x4 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=132 smt_wct=3 smb_vwv[ 0]=19386 (0x4BBA) smb_vwv[ 1]=65535 (0xFFFF) smb_vwv[ 2]=65535 (0xFFFF) smb_bcc=0 [2010/03/11 09:07:17, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:17, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:17, 3] smbd/reply.c:4488(reply_close) close fd=31 fnum=19386 (numopen=1) [2010/03/11 09:07:17, 6] smbd/close.c:454(set_close_write_time) close_write_time: Sun Feb 7 00:28:15 2106 [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 00FD0000000000005C8C [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x7f26626c3c10 [2010/03/11 09:07:17, 10] locking/locking.c:552(parse_share_modes) parse_share_modes: delete_on_close: 0, owrt: Tue 19 Jan 2010 07:34:11 PM CST CST, cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num_share_modes: 1 [2010/03/11 09:07:17, 10] locking/locking.c:649(parse_share_modes) parse_share_modes: share_mode_entry[0]: pid = 31889, share_access = 0x7, private_options = 0x0, access_mask = 0x20081, mid = 0x0, type= 0x10, gen_id = 9, uid = 500, flags = 0, file_id fd00:3c18c5c:0 [2010/03/11 09:07:17, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 00FD0000000000005C8C [2010/03/11 09:07:17, 10] locking/posix.c:495(get_windows_lock_ref_count) get_windows_lock_count for file table_template.inc.php = 0 [2010/03/11 09:07:17, 10] locking/posix.c:521(delete_windows_lock_ref_count) delete_windows_lock_ref_count for file table_template.inc.php [2010/03/11 09:07:17, 2] smbd/close.c:612(close_normal_file) jonb closed file table_template.inc.php (numopen=0) NT_STATUS_OK [2010/03/11 09:07:17, 5] smbd/files.c:474(file_free) freed files structure 19386 (0 used) [2010/03/11 09:07:17, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:17, 5] lib/util.c:642(show_msg) size=35 smb_com=0x4 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=132 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:25, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 100 [2010/03/11 09:07:25, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x64 [2010/03/11 09:07:25, 3] smbd/process.c:1459(process_smb) Transaction 133 of length 104 (0 toread) [2010/03/11 09:07:25, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:25, 5] lib/util.c:642(show_msg) size=100 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=133 smt_wct=15 smb_vwv[ 0]= 32 (0x20) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 10 (0xA) smb_vwv[ 3]=16644 (0x4104) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 32 (0x20) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 1 (0x1) smb_bcc=35 [2010/03/11 09:07:25, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 16 00 10 00 06 00 04 01 00 00 00 00 5C ........ .......\ [0010] 00 61 00 70 00 61 00 63 00 68 00 65 00 5C 00 2A .a.p.a.c .h.e.\.* [0020] 00 00 00 ... [2010/03/11 09:07:25, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:25, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:25, 3] smbd/trans2.c:1924(call_trans2findfirst) call_trans2findfirst: dirtype = 16, maxentries = 16, close_after_first=0, close_if_end = 1 requires_resume_key = 1 level = 0x104, max_data_bytes = 16644 [2010/03/11 09:07:25, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "apache/*" [2010/03/11 09:07:25, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [APACHE/*] [2010/03/11 09:07:25, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [APACHE] [2010/03/11 09:07:25, 5] smbd/filename.c:303(unix_convert) unix_convert begin: name = apache/*, dirpath = , start = apache/* [2010/03/11 09:07:25, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled apache/* ? [2010/03/11 09:07:25, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component apache/* (len 6) ? [2010/03/11 09:07:25, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component * (len 1) ? [2010/03/11 09:07:25, 5] smbd/statcache.c:138(stat_cache_add) stat_cache_add: Added entry (7f26626c3df0:size 6) APACHE -> apache [2010/03/11 09:07:25, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled * ? [2010/03/11 09:07:25, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component * (len 1) ? [2010/03/11 09:07:25, 5] smbd/filename.c:564(unix_convert) New file * [2010/03/11 09:07:25, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [apache/*] [/home/jonb] [2010/03/11 09:07:25, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [apache/*] -> [/home/apache/html/*] [2010/03/11 09:07:25, 2] smbd/vfs.c:949(check_reduced_name) reduce_name: Bad access attempt: apache/* is a symlink outside the share path [2010/03/11 09:07:25, 5] smbd/filename.c:768(check_name) check_name: name apache/* failed with NT_STATUS_ACCESS_DENIED [2010/03/11 09:07:25, 3] smbd/error.c:60(error_packet_set) error packet at smbd/trans2.c(1987) cmd=50 (SMBtrans2) NT_STATUS_ACCESS_DENIED [2010/03/11 09:07:25, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:25, 5] lib/util.c:642(show_msg) size=35 smb_com=0x32 smb_rcls=34 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=133 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:25, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 37 [2010/03/11 09:07:25, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x25 [2010/03/11 09:07:25, 3] smbd/process.c:1459(process_smb) Transaction 134 of length 41 (0 toread) [2010/03/11 09:07:25, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:25, 5] lib/util.c:642(show_msg) size=37 smb_com=0x34 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=134 smt_wct=1 smb_vwv[ 0]= 0 (0x0) smb_bcc=0 [2010/03/11 09:07:25, 3] smbd/process.c:1273(switch_message) switch message SMBfindclose (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:25, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:25, 3] smbd/trans2.c:7464(reply_findclose) reply_findclose, dptr_num = 0 [2010/03/11 09:07:25, 0] smbd/dir.c:292(dptr_close) Invalid key 0 given to dptr_close [2010/03/11 09:07:25, 3] smbd/trans2.c:7470(reply_findclose) SMBfindclose dptr_num = 0 [2010/03/11 09:07:25, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:25, 5] lib/util.c:642(show_msg) size=35 smb_com=0x34 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=134 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:41, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 78 [2010/03/11 09:07:41, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x4e [2010/03/11 09:07:41, 3] smbd/process.c:1459(process_smb) Transaction 135 of length 82 (0 toread) [2010/03/11 09:07:41, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:41, 5] lib/util.c:642(show_msg) size=78 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=135 smt_wct=15 smb_vwv[ 0]= 10 (0xA) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 2 (0x2) smb_vwv[ 3]=16644 (0x4104) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 10 (0xA) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=13 [2010/03/11 09:07:41, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 01 01 00 00 00 00 5C 00 00 00 ........ .\... [2010/03/11 09:07:41, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:41, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:41, 3] smbd/trans2.c:3956(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 257 [2010/03/11 09:07:41, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "" [2010/03/11 09:07:41, 5] smbd/filename.c:181(unix_convert) conversion finished "" -> . [2010/03/11 09:07:41, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [.] [/home/jonb] [2010/03/11 09:07:41, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [.] -> [/home/jonb] [2010/03/11 09:07:41, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: . reduced to /home/jonb [2010/03/11 09:07:41, 10] locking/locking.c:891(fetch_share_mode_unlocked) fetch_share_mode_unlocked: no share_mode record around (file not open) [2010/03/11 09:07:41, 3] smbd/trans2.c:4070(call_trans2qfilepathinfo) call_trans2qfilepathinfo . (fnum = -1) level=257 call=5 total_data=0 [2010/03/11 09:07:41, 8] smbd/dosmode.c:494(dos_mode) dos_mode: . [2010/03/11 09:07:41, 8] smbd/dosmode.c:189(dos_mode_from_sbuf) dos_mode_from_sbuf returning d [2010/03/11 09:07:41, 8] smbd/dosmode.c:547(dos_mode) dos_mode returning d [2010/03/11 09:07:41, 10] smbd/trans2.c:4307(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_QUERY_FILE_BASIC_INFO [2010/03/11 09:07:41, 5] smbd/trans2.c:4320(call_trans2qfilepathinfo) SMB_QFBI - create: Thu Mar 11 09:04:56 2010 access: Thu Mar 11 09:07:15 2010 write: Thu Mar 11 09:04:56 2010 change: Thu Mar 11 09:04:56 2010 mode: 10 [2010/03/11 09:07:41, 9] smbd/trans2.c:820(send_trans2_replies) t2_rep: params_sent_thistime = 2, data_sent_thistime = 36, useable_space = 16582 [2010/03/11 09:07:41, 9] smbd/trans2.c:822(send_trans2_replies) t2_rep: params_to_send = 2, data_to_send = 36, paramsize = 2, datasize = 36 [2010/03/11 09:07:41, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:41, 5] lib/util.c:642(show_msg) size=96 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=135 smt_wct=10 smb_vwv[ 0]= 2 (0x2) smb_vwv[ 1]= 36 (0x24) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 2 (0x2) smb_vwv[ 4]= 56 (0x38) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 36 (0x24) smb_vwv[ 7]= 60 (0x3C) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_bcc=41 [2010/03/11 09:07:41, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 00 00 00 DC DE 35 2C C1 CA 01 80 93 B8 ........ 5,...... [0010] 88 2C C1 CA 01 00 DC DE 35 2C C1 CA 01 00 DC DE .,...... 5,...... [0020] 35 2C C1 CA 01 10 00 00 00 5,...... . [2010/03/11 09:07:41, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 132 [2010/03/11 09:07:41, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x84 [2010/03/11 09:07:41, 3] smbd/process.c:1459(process_smb) Transaction 136 of length 136 (0 toread) [2010/03/11 09:07:41, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:41, 5] lib/util.c:642(show_msg) size=132 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=136 smt_wct=15 smb_vwv[ 0]= 64 (0x40) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 2 (0x2) smb_vwv[ 3]=16644 (0x4104) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 64 (0x40) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=67 [2010/03/11 09:07:41, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 07 01 00 00 00 00 5C 00 2E 00 54 00 65 ........ .\...T.e [0010] 00 6D 00 70 00 6F 00 72 00 61 00 72 00 79 00 49 .m.p.o.r .a.r.y.I [0020] 00 74 00 65 00 6D 00 73 00 5C 00 66 00 6F 00 6C .t.e.m.s .\.f.o.l [0030] 00 64 00 65 00 72 00 73 00 2E 00 35 00 30 00 32 .d.e.r.s ...5.0.2 [0040] 00 00 00 ... [2010/03/11 09:07:41, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:41, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:41, 3] smbd/trans2.c:3956(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263 [2010/03/11 09:07:41, 5] smbd/filename.c:148(unix_convert) unix_convert called on file ".TemporaryItems/folders.502" [2010/03/11 09:07:41, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [.TEMPORARYITEMS/FOLDERS.502] [2010/03/11 09:07:41, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [.TEMPORARYITEMS] [2010/03/11 09:07:41, 5] smbd/statcache.c:138(stat_cache_add) stat_cache_add: Added entry (7f26626c3ca0:size 1b) .TEMPORARYITEMS/FOLDERS.502 -> .TemporaryItems/folders.502 [2010/03/11 09:07:41, 5] smbd/filename.c:297(unix_convert) conversion finished .TemporaryItems/folders.502 -> .TemporaryItems/folders.502 [2010/03/11 09:07:41, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [.TemporaryItems/folders.502] [/home/jonb] [2010/03/11 09:07:41, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [.TemporaryItems/folders.502] -> [/home/jonb/.TemporaryItems/folders.502] [2010/03/11 09:07:41, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: .TemporaryItems/folders.502 reduced to /home/jonb/.TemporaryItems/folders.502 [2010/03/11 09:07:41, 10] locking/locking.c:891(fetch_share_mode_unlocked) fetch_share_mode_unlocked: no share_mode record around (file not open) [2010/03/11 09:07:41, 3] smbd/trans2.c:4070(call_trans2qfilepathinfo) call_trans2qfilepathinfo .TemporaryItems/folders.502 (fnum = -1) level=263 call=5 total_data=0 [2010/03/11 09:07:41, 8] smbd/dosmode.c:494(dos_mode) dos_mode: .TemporaryItems/folders.502 [2010/03/11 09:07:41, 8] smbd/dosmode.c:189(dos_mode_from_sbuf) dos_mode_from_sbuf returning d [2010/03/11 09:07:41, 8] smbd/dosmode.c:547(dos_mode) dos_mode returning d [2010/03/11 09:07:41, 10] smbd/trans2.c:4403(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_FILE_ALL_INFORMATION [2010/03/11 09:07:41, 9] smbd/trans2.c:820(send_trans2_replies) t2_rep: params_sent_thistime = 2, data_sent_thistime = 128, useable_space = 16582 [2010/03/11 09:07:41, 9] smbd/trans2.c:822(send_trans2_replies) t2_rep: params_to_send = 2, data_to_send = 128, paramsize = 2, datasize = 128 [2010/03/11 09:07:41, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:41, 5] lib/util.c:642(show_msg) size=188 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=136 smt_wct=10 smb_vwv[ 0]= 2 (0x2) smb_vwv[ 1]= 128 (0x80) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 2 (0x2) smb_vwv[ 4]= 56 (0x38) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 128 (0x80) smb_vwv[ 7]= 60 (0x3C) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_bcc=133 [2010/03/11 09:07:41, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 00 00 00 0B D7 78 B9 A6 CA 01 00 37 1C ........ x.....7. [0010] C0 7D C0 CA 01 00 0B D7 78 B9 A6 CA 01 00 0B D7 .}...... x....... [0020] 78 B9 A6 CA 01 10 00 00 00 00 00 00 00 00 00 00 x....... ........ [0030] 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 00 ........ ........ [0040] 00 00 01 00 00 00 00 00 00 38 00 00 00 5C 00 2E ........ .8...\.. [0050] 00 54 00 65 00 6D 00 70 00 6F 00 72 00 61 00 72 .T.e.m.p .o.r.a.r [0060] 00 79 00 49 00 74 00 65 00 6D 00 73 00 5C 00 66 .y.I.t.e .m.s.\.f [0070] 00 6F 00 6C 00 64 00 65 00 72 00 73 00 2E 00 35 .o.l.d.e .r.s...5 [0080] 00 30 00 32 00 .0.2. [2010/03/11 09:07:41, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 136 [2010/03/11 09:07:41, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x88 [2010/03/11 09:07:41, 3] smbd/process.c:1459(process_smb) Transaction 137 of length 140 (0 toread) [2010/03/11 09:07:41, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:41, 5] lib/util.c:642(show_msg) size=136 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=137 smt_wct=15 smb_vwv[ 0]= 68 (0x44) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 2 (0x2) smb_vwv[ 3]=16644 (0x4104) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 68 (0x44) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=71 [2010/03/11 09:07:41, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 07 01 00 00 00 00 5C 00 2E 00 54 00 65 ........ .\...T.e [0010] 00 6D 00 70 00 6F 00 72 00 61 00 72 00 79 00 49 .m.p.o.r .a.r.y.I [0020] 00 74 00 65 00 6D 00 73 00 5C 00 2E 00 5F 00 66 .t.e.m.s .\..._.f [0030] 00 6F 00 6C 00 64 00 65 00 72 00 73 00 2E 00 35 .o.l.d.e .r.s...5 [0040] 00 30 00 32 00 00 00 .0.2... [2010/03/11 09:07:41, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:41, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:41, 3] smbd/trans2.c:3956(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263 [2010/03/11 09:07:41, 5] smbd/filename.c:148(unix_convert) unix_convert called on file ".TemporaryItems/._folders.502" [2010/03/11 09:07:41, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [.TEMPORARYITEMS/._FOLDERS.502] [2010/03/11 09:07:41, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [.TEMPORARYITEMS] [2010/03/11 09:07:41, 5] smbd/filename.c:303(unix_convert) unix_convert begin: name = .TemporaryItems/._folders.502, dirpath = , start = .TemporaryItems/._folders.502 [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled .TemporaryItems/._folders.502 ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component .TemporaryItems/._folders.502 (len 15) ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._folders.502 (len 13) ? [2010/03/11 09:07:41, 5] smbd/statcache.c:138(stat_cache_add) stat_cache_add: Added entry (7f26626c3df0:size f) .TEMPORARYITEMS -> .TemporaryItems [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._folders.502 ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._folders.502 (len 13) ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled ._folders.502 ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component ._folders.502 (len 13) ? [2010/03/11 09:07:41, 5] smbd/filename.c:564(unix_convert) New file ._folders.502 [2010/03/11 09:07:41, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [.TemporaryItems/._folders.502] [/home/jonb] [2010/03/11 09:07:41, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [.TemporaryItems/._folders.502] -> [/home/jonb/.TemporaryItems/._folders.502] [2010/03/11 09:07:41, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: .TemporaryItems/._folders.502 reduced to /home/jonb/.TemporaryItems/._folders.502 [2010/03/11 09:07:41, 3] smbd/trans2.c:4050(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_VFS_STAT of .TemporaryItems/._folders.502 failed (No such file or directory) [2010/03/11 09:07:41, 3] smbd/error.c:153(reply_unix_error) unix_error_packet: error string = No such file or directory [2010/03/11 09:07:41, 3] smbd/error.c:60(error_packet_set) error packet at smbd/trans2.c(4051) cmd=50 (SMBtrans2) NT_STATUS_OBJECT_NAME_NOT_FOUND [2010/03/11 09:07:41, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:41, 5] lib/util.c:642(show_msg) size=35 smb_com=0x32 smb_rcls=52 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=137 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:41, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 170 [2010/03/11 09:07:41, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0xaa [2010/03/11 09:07:41, 3] smbd/process.c:1459(process_smb) Transaction 138 of length 174 (0 toread) [2010/03/11 09:07:41, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:41, 5] lib/util.c:642(show_msg) size=170 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=138 smt_wct=15 smb_vwv[ 0]= 102 (0x66) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 2 (0x2) smb_vwv[ 3]=16644 (0x4104) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 102 (0x66) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=105 [2010/03/11 09:07:41, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 07 01 00 00 00 00 5C 00 2E 00 54 00 65 ........ .\...T.e [0010] 00 6D 00 70 00 6F 00 72 00 61 00 72 00 79 00 49 .m.p.o.r .a.r.y.I [0020] 00 74 00 65 00 6D 00 73 00 5C 00 66 00 6F 00 6C .t.e.m.s .\.f.o.l [0030] 00 64 00 65 00 72 00 73 00 2E 00 35 00 30 00 32 .d.e.r.s ...5.0.2 [0040] 00 5C 00 43 00 6C 00 65 00 61 00 6E 00 75 00 70 .\.C.l.e .a.n.u.p [0050] 00 20 00 41 00 74 00 20 00 53 00 74 00 61 00 72 . .A.t. .S.t.a.r [0060] 00 74 00 75 00 70 00 00 00 .t.u.p.. . [2010/03/11 09:07:41, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:41, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:41, 3] smbd/trans2.c:3956(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263 [2010/03/11 09:07:41, 5] smbd/filename.c:148(unix_convert) unix_convert called on file ".TemporaryItems/folders.502/Cleanup At Startup" [2010/03/11 09:07:41, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [.TEMPORARYITEMS/FOLDERS.502/CLEANUP AT STARTUP] [2010/03/11 09:07:41, 10] smbd/statcache.c:274(stat_cache_lookup) stat_cache_lookup: lookup succeeded for name [.TEMPORARYITEMS/FOLDERS.502] -> [.TemporaryItems/folders.502] [2010/03/11 09:07:41, 5] smbd/filename.c:303(unix_convert) unix_convert begin: name = .TemporaryItems/folders.502/Cleanup At Startup, dirpath = .TemporaryItems/folders.502, start = Cleanup At Startup [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled Cleanup At Startup ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component Cleanup At Startup (len 18) ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled Cleanup At Startup ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component Cleanup At Startup (len 18) ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled Cleanup At Startup ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component Cleanup At Startup (len 18) ? [2010/03/11 09:07:41, 5] smbd/filename.c:564(unix_convert) New file Cleanup At Startup [2010/03/11 09:07:41, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [.TemporaryItems/folders.502/Cleanup At Startup] [/home/jonb] [2010/03/11 09:07:41, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [.TemporaryItems/folders.502/Cleanup At Startup] -> [/home/jonb/.TemporaryItems/folders.502/Cleanup At Startup] [2010/03/11 09:07:41, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: .TemporaryItems/folders.502/Cleanup At Startup reduced to /home/jonb/.TemporaryItems/folders.502/Cleanup At Startup [2010/03/11 09:07:41, 3] smbd/trans2.c:4050(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_VFS_STAT of .TemporaryItems/folders.502/Cleanup At Startup failed (No such file or directory) [2010/03/11 09:07:41, 3] smbd/error.c:153(reply_unix_error) unix_error_packet: error string = No such file or directory [2010/03/11 09:07:41, 3] smbd/error.c:60(error_packet_set) error packet at smbd/trans2.c(4051) cmd=50 (SMBtrans2) NT_STATUS_OBJECT_NAME_NOT_FOUND [2010/03/11 09:07:41, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:41, 5] lib/util.c:642(show_msg) size=35 smb_com=0x32 smb_rcls=52 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=138 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:41, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 94 [2010/03/11 09:07:41, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x5e [2010/03/11 09:07:41, 3] smbd/process.c:1459(process_smb) Transaction 139 of length 98 (0 toread) [2010/03/11 09:07:41, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:41, 5] lib/util.c:642(show_msg) size=94 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=139 smt_wct=15 smb_vwv[ 0]= 26 (0x1A) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 2 (0x2) smb_vwv[ 3]=16644 (0x4104) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 26 (0x1A) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=29 [2010/03/11 09:07:41, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 07 01 00 00 00 00 5C 00 2E 00 54 00 72 ........ .\...T.r [0010] 00 61 00 73 00 68 00 65 00 73 00 00 00 .a.s.h.e .s... [2010/03/11 09:07:41, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:41, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:41, 3] smbd/trans2.c:3956(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263 [2010/03/11 09:07:41, 5] smbd/filename.c:148(unix_convert) unix_convert called on file ".Trashes" [2010/03/11 09:07:41, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [.TRASHES] [2010/03/11 09:07:41, 5] smbd/filename.c:303(unix_convert) unix_convert begin: name = .Trashes, dirpath = , start = .Trashes [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled .Trashes ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component .Trashes (len 8) ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled .Trashes ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component .Trashes (len 8) ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled .Trashes ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component .Trashes (len 8) ? [2010/03/11 09:07:41, 5] smbd/filename.c:564(unix_convert) New file .Trashes [2010/03/11 09:07:41, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [.Trashes] [/home/jonb] [2010/03/11 09:07:41, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [.Trashes] -> [/home/jonb/.Trashes] [2010/03/11 09:07:41, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: .Trashes reduced to /home/jonb/.Trashes [2010/03/11 09:07:41, 3] smbd/trans2.c:4050(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_VFS_STAT of .Trashes failed (No such file or directory) [2010/03/11 09:07:41, 3] smbd/error.c:153(reply_unix_error) unix_error_packet: error string = No such file or directory [2010/03/11 09:07:41, 3] smbd/error.c:60(error_packet_set) error packet at smbd/trans2.c(4051) cmd=50 (SMBtrans2) NT_STATUS_OBJECT_NAME_NOT_FOUND [2010/03/11 09:07:41, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:41, 5] lib/util.c:642(show_msg) size=35 smb_com=0x32 smb_rcls=52 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=139 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:41, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 94 [2010/03/11 09:07:41, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x5e [2010/03/11 09:07:41, 3] smbd/process.c:1459(process_smb) Transaction 140 of length 98 (0 toread) [2010/03/11 09:07:41, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:41, 5] lib/util.c:642(show_msg) size=94 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=140 smt_wct=15 smb_vwv[ 0]= 26 (0x1A) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 2 (0x2) smb_vwv[ 3]=16644 (0x4104) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 26 (0x1A) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=29 [2010/03/11 09:07:41, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 07 01 00 00 00 00 5C 00 2E 00 54 00 72 ........ .\...T.r [0010] 00 61 00 73 00 68 00 65 00 73 00 00 00 .a.s.h.e .s... [2010/03/11 09:07:41, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:41, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/03/11 09:07:41, 3] smbd/trans2.c:3956(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263 [2010/03/11 09:07:41, 5] smbd/filename.c:148(unix_convert) unix_convert called on file ".Trashes" [2010/03/11 09:07:41, 10] smbd/statcache.c:235(stat_cache_lookup) stat_cache_lookup: lookup failed for name [.TRASHES] [2010/03/11 09:07:41, 5] smbd/filename.c:303(unix_convert) unix_convert begin: name = .Trashes, dirpath = , start = .Trashes [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled .Trashes ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component .Trashes (len 8) ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled .Trashes ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component .Trashes (len 8) ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:239(is_mangled) is_mangled .Trashes ? [2010/03/11 09:07:41, 10] smbd/mangle_hash2.c:178(is_mangled_component) is_mangled_component .Trashes (len 8) ? [2010/03/11 09:07:41, 5] smbd/filename.c:564(unix_convert) New file .Trashes [2010/03/11 09:07:41, 3] smbd/vfs.c:865(check_reduced_name) reduce_name [.Trashes] [/home/jonb] [2010/03/11 09:07:41, 10] smbd/vfs.c:937(check_reduced_name) reduce_name realpath [.Trashes] -> [/home/jonb/.Trashes] [2010/03/11 09:07:41, 3] smbd/vfs.c:974(check_reduced_name) reduce_name: .Trashes reduced to /home/jonb/.Trashes [2010/03/11 09:07:41, 3] smbd/trans2.c:4050(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_VFS_STAT of .Trashes failed (No such file or directory) [2010/03/11 09:07:41, 3] smbd/error.c:153(reply_unix_error) unix_error_packet: error string = No such file or directory [2010/03/11 09:07:41, 3] smbd/error.c:60(error_packet_set) error packet at smbd/trans2.c(4051) cmd=50 (SMBtrans2) NT_STATUS_OBJECT_NAME_NOT_FOUND [2010/03/11 09:07:41, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:41, 5] lib/util.c:642(show_msg) size=35 smb_com=0x32 smb_rcls=52 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=140 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:41, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 35 [2010/03/11 09:07:41, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x23 [2010/03/11 09:07:41, 3] smbd/process.c:1459(process_smb) Transaction 141 of length 39 (0 toread) [2010/03/11 09:07:41, 5] lib/util.c:632(show_msg) [2010/03/11 09:07:41, 5] lib/util.c:642(show_msg) size=35 smb_com=0x71 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=49153 smb_tid=1 smb_pid=1 smb_uid=100 smb_mid=141 smt_wct=0 smb_bcc=0 [2010/03/11 09:07:41, 3] smbd/process.c:1273(switch_message) switch message SMBtdis (pid 31889) conn 0x7f26626e5a50 [2010/03/11 09:07:41, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/03/11 09:07:41, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2010/03/11 09:07:41, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups