[2010/02/03 18:31:59, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2010/02/03 18:31:59, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/02/03 18:31:59, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2010/02/03 18:31:59, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:572(pdb_set_username) pdb_set_username: setting username joerg, was [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:595(pdb_set_domain) pdb_set_domain: setting domain NEPTUN, was [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:618(pdb_set_nt_username) pdb_set_nt_username: setting nt username , was [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:641(pdb_set_fullname) pdb_set_full_name: setting full name Jörg Sommer, was [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:734(pdb_set_homedir) pdb_set_homedir: setting home dir \\cifs-homes\all-homes\joerg, was [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:710(pdb_set_dir_drive) pdb_set_dir_drive: setting dir drive , was NULL [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:664(pdb_set_logon_script) pdb_set_logon_script: setting logon script , was [2010/02/03 18:31:59, 4] lib/substitute.c:504(automount_server) Home server: neptun [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:687(pdb_set_profile_path) pdb_set_profile_path: setting profile path \\neptun\joerg\profile, was [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:777(pdb_set_workstations) pdb_set_workstations: setting workstations , was [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2010/02/03 18:31:59, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 2 [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2010/02/03 18:31:59, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2010/02/03 18:31:59, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2010/02/03 18:31:59, 10] lib/account_pol.c:333(account_policy_get) account_policy_get: name: password history, val: 0 [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:501(pdb_set_user_sid) pdb_set_user_sid: setting user sid S-1-5-21-3690565184-454682460-1052817659-3000 [2010/02/03 18:31:59, 10] passdb/pdb_compat.c:72(pdb_set_user_sid_from_rid) pdb_set_user_sid_from_rid: setting user sid S-1-5-21-3690565184-454682460-1052817659-3000 from rid 3000 [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (1000, 4) - sec_ctx_stack_ndx = 0 [2010/02/03 18:31:59, 10] passdb/lookup_sid.c:1142(legacy_uid_to_sid) LEGACY: uid 1000 -> sid S-1-5-21-3690565184-454682460-1052817659-3000 [2010/02/03 18:31:59, 10] lib/gencache.c:194(gencache_get) Cache entry with key = IDMAP/UID2SID/33 couldn't be found [2010/02/03 18:31:59, 5] passdb/lookup_sid.c:1325(uid_to_sid) uid_to_sid: winbind failed to find a sid for uid 33 [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(1000, 4) : sec_ctx_stack_ndx = 1 [2010/02/03 18:31:59, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/02/03 18:31:59, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2010/02/03 18:31:59, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2010/02/03 18:31:59, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/02/03 18:31:59, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2010/02/03 18:31:59, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:572(pdb_set_username) pdb_set_username: setting username www-data, was [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:595(pdb_set_domain) pdb_set_domain: setting domain NEPTUN, was [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:618(pdb_set_nt_username) pdb_set_nt_username: setting nt username , was [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:641(pdb_set_fullname) pdb_set_full_name: setting full name Webserver, was [2010/02/03 18:31:59, 4] lib/substitute.c:504(automount_server) Home server: neptun [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:734(pdb_set_homedir) pdb_set_homedir: setting home dir \\neptun\www-data, was [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:710(pdb_set_dir_drive) pdb_set_dir_drive: setting dir drive , was NULL [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:664(pdb_set_logon_script) pdb_set_logon_script: setting logon script , was [2010/02/03 18:31:59, 4] lib/substitute.c:504(automount_server) Home server: neptun [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:687(pdb_set_profile_path) pdb_set_profile_path: setting profile path \\neptun\www-data\profile, was [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:777(pdb_set_workstations) pdb_set_workstations: setting workstations , was [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2010/02/03 18:31:59, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 2 [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2010/02/03 18:31:59, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2010/02/03 18:31:59, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2010/02/03 18:31:59, 10] lib/account_pol.c:333(account_policy_get) account_policy_get: name: password history, val: 0 [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:501(pdb_set_user_sid) pdb_set_user_sid: setting user sid S-1-5-21-3690565184-454682460-1052817659-1066 [2010/02/03 18:31:59, 10] passdb/pdb_compat.c:72(pdb_set_user_sid_from_rid) pdb_set_user_sid_from_rid: setting user sid S-1-5-21-3690565184-454682460-1052817659-1066 from rid 1066 [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (1000, 4) - sec_ctx_stack_ndx = 0 [2010/02/03 18:31:59, 10] passdb/lookup_sid.c:1142(legacy_uid_to_sid) LEGACY: uid 33 -> sid S-1-5-21-3690565184-454682460-1052817659-1066 [2010/02/03 18:31:59, 10] smbd/posix_acls.c:2498(canonicalise_acl) canonicalise_acl: Access ace entries before arrange : [2010/02/03 18:31:59, 10] smbd/posix_acls.c:2511(canonicalise_acl) canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms --- [2010/02/03 18:31:59, 10] smbd/posix_acls.c:2511(canonicalise_acl) canon_ace index 1. Type = allow SID = S-1-5-21-3690565184-454682460-1052817659-513 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms --- [2010/02/03 18:31:59, 10] smbd/posix_acls.c:2511(canonicalise_acl) canon_ace index 2. Type = allow SID = S-1-5-21-3690565184-454682460-1052817659-1066 uid 33 (www-data) SMB_ACL_USER ace_flags = 0x0 perms --x [2010/02/03 18:31:59, 10] smbd/posix_acls.c:2511(canonicalise_acl) canon_ace index 3. Type = allow SID = S-1-5-21-3690565184-454682460-1052817659-3000 uid 1000 (joerg) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx [2010/02/03 18:31:59, 10] smbd/posix_acls.c:817(print_canon_ace_list) print_canon_ace_list: canonicalise_acl: ace entries after arrange canon_ace index 0. Type = allow SID = S-1-5-21-3690565184-454682460-1052817659-3000 uid 1000 (joerg) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx canon_ace index 1. Type = allow SID = S-1-5-21-3690565184-454682460-1052817659-513 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms --- canon_ace index 2. Type = allow SID = S-1-5-21-3690565184-454682460-1052817659-1066 uid 33 (www-data) SMB_ACL_USER ace_flags = 0x0 perms --x canon_ace index 3. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms --- [2010/02/03 18:31:59, 10] smbd/posix_acls.c:1092(map_canon_ace_perms) map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff [2010/02/03 18:31:59, 10] smbd/posix_acls.c:1092(map_canon_ace_perms) map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0 [2010/02/03 18:31:59, 10] smbd/posix_acls.c:1092(map_canon_ace_perms) map_canon_ace_perms: Mapped (UNIX) 40 to (NT) 1200a0 [2010/02/03 18:31:59, 10] smbd/posix_acls.c:1092(map_canon_ace_perms) map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0 [2010/02/03 18:31:59, 4] smbd/open.c:1909(open_file_ntcreate) calling open_file with flags=0x1 flags2=0x240 mode=0666, access_mask = 0x120116, open_access_mask = 0x120116 [2010/02/03 18:31:59, 10] smbd/open.c:160(fd_open) fd_open: name joerg/test, flags = 0101 mode = 0666, fd = 30. [2010/02/03 18:31:59, 2] smbd/open.c:580(open_file) joerg opened file joerg/test read=No write=Yes (numopen=1) [2010/02/03 18:31:59, 10] smbd/close.c:198(delete_all_streams) delete_all_streams found 1 streams [2010/02/03 18:31:59, 5] smbd/oplock.c:86(set_file_oplock) set_file_oplock: granted oplock on file joerg/test, 305:34dad6:0/1, tv_sec = 4b69b30f, tv_usec = 9a3f2 [2010/02/03 18:31:59, 10] locking/locking.c:716(unparse_share_modes) unparse_share_modes: del: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET cwrt: Do 01 Jan 1970 01:00:00 CET CET, tok: 0, num: 1 [2010/02/03 18:31:59, 10] locking/locking.c:518(print_share_mode_table) print_share_mode_table: share_mode_entry[0]: pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0 [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 0503000000000000D6DA [2010/02/03 18:31:59, 10] smbd/open.c:3197(create_file_unixpath) create_file_unixpath: info=3 [2010/02/03 18:31:59, 10] smbd/open.c:3492(create_file_default) create_file: info=3 [2010/02/03 18:31:59, 8] smbd/dosmode.c:474(dos_mode) dos_mode: joerg/test [2010/02/03 18:31:59, 8] smbd/dosmode.c:175(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/02/03 18:31:59, 8] smbd/dosmode.c:521(dos_mode) dos_mode returning [2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 98 [2010/02/03 18:31:59, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x62 [2010/02/03 18:31:59, 3] smbd/process.c:1459(process_smb) Transaction 6 of length 102 (0 toread) [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=98 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=7 smt_wct=15 smb_vwv[ 0]= 30 (0x1E) 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]= 30 (0x1E) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 98 (0x62) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=33 [2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 44 20 07 01 00 00 00 00 5C 00 6A 00 6F 00 65 .D ..... .\.j.o.e [0010] 00 72 00 67 00 5C 00 74 00 65 00 73 00 74 00 00 .r.g.\.t .e.s.t.. [0020] 00 . [2010/02/03 18:31:59, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 18788) conn 0x89fd6b0 [2010/02/03 18:31:59, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/02/03 18:31:59, 3] smbd/trans2.c:3955(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263 [2010/02/03 18:31:59, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "joerg/test" [2010/02/03 18:31:59, 5] smbd/filename.c:297(unix_convert) conversion finished joerg/test -> joerg/test [2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes) parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Do 01 Jan 1970 01:00:00 CET CET, tok: 0, num_share_modes: 1 [2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes) parse_share_modes: share_mode_entry[0]: pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0 [2010/02/03 18:31:59, 3] smbd/trans2.c:4069(call_trans2qfilepathinfo) call_trans2qfilepathinfo joerg/test (fnum = -1) level=263 call=5 total_data=0 [2010/02/03 18:31:59, 8] smbd/dosmode.c:474(dos_mode) dos_mode: joerg/test [2010/02/03 18:31:59, 8] smbd/dosmode.c:175(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/02/03 18:31:59, 8] smbd/dosmode.c:521(dos_mode) dos_mode returning [2010/02/03 18:31:59, 10] smbd/trans2.c:204(get_ea_names_from_file) get_ea_list_from_file: ea_namelist size = 0 [2010/02/03 18:31:59, 10] smbd/trans2.c:4404(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_FILE_ALL_INFORMATION [2010/02/03 18:31:59, 9] smbd/trans2.c:820(send_trans2_replies) t2_rep: params_sent_thistime = 2, data_sent_thistime = 94, useable_space = 131010 [2010/02/03 18:31:59, 9] smbd/trans2.c:822(send_trans2_replies) t2_rep: params_to_send = 2, data_to_send = 94, paramsize = 2, datasize = 94 [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=154 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=7 smt_wct=10 smb_vwv[ 0]= 2 (0x2) smb_vwv[ 1]= 94 (0x5E) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 2 (0x2) smb_vwv[ 4]= 56 (0x38) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 94 (0x5E) smb_vwv[ 7]= 60 (0x3C) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_bcc=99 [2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 00 00 80 4F 1D D9 F5 A4 CA 01 80 4F 1D ......O. ......O. [0010] D9 F5 A4 CA 01 00 59 AA FE F5 A4 CA 01 00 59 AA ......Y. ......Y. [0020] FE F5 A4 CA 01 80 00 00 00 00 00 00 00 00 00 00 ........ ........ [0030] 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 00 ........ ........ [0040] 00 00 00 00 00 00 00 00 00 16 00 00 00 5C 00 6A ........ .....\.j [0050] 00 6F 00 65 00 72 00 67 00 5C 00 74 00 65 00 73 .o.e.r.g .\.t.e.s [0060] 00 74 00 .t. [2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 72 [2010/02/03 18:31:59, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x48 [2010/02/03 18:31:59, 3] smbd/process.c:1459(process_smb) Transaction 7 of length 76 (0 toread) [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=72 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=8 smt_wct=15 smb_vwv[ 0]= 4 (0x4) 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]= 4 (0x4) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 72 (0x48) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 7 (0x7) smb_bcc=7 [2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 44 20 6B 12 07 01 .D k... [2010/02/03 18:31:59, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 18788) conn 0x89fd6b0 [2010/02/03 18:31:59, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/02/03 18:31:59, 3] smbd/trans2.c:3879(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 263 [2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes) parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Do 01 Jan 1970 01:00:00 CET CET, tok: 0, num_share_modes: 1 [2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes) parse_share_modes: share_mode_entry[0]: pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0 [2010/02/03 18:31:59, 3] smbd/trans2.c:4069(call_trans2qfilepathinfo) call_trans2qfilepathinfo joerg/test (fnum = 4715) level=263 call=7 total_data=0 [2010/02/03 18:31:59, 8] smbd/dosmode.c:474(dos_mode) dos_mode: joerg/test [2010/02/03 18:31:59, 8] smbd/dosmode.c:175(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/02/03 18:31:59, 8] smbd/dosmode.c:521(dos_mode) dos_mode returning [2010/02/03 18:31:59, 10] smbd/trans2.c:204(get_ea_names_from_file) get_ea_list_from_file: ea_namelist size = 0 [2010/02/03 18:31:59, 10] smbd/trans2.c:4404(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_FILE_ALL_INFORMATION [2010/02/03 18:31:59, 9] smbd/trans2.c:820(send_trans2_replies) t2_rep: params_sent_thistime = 2, data_sent_thistime = 94, useable_space = 131010 [2010/02/03 18:31:59, 9] smbd/trans2.c:822(send_trans2_replies) t2_rep: params_to_send = 2, data_to_send = 94, paramsize = 2, datasize = 94 [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=154 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=8 smt_wct=10 smb_vwv[ 0]= 2 (0x2) smb_vwv[ 1]= 94 (0x5E) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 2 (0x2) smb_vwv[ 4]= 56 (0x38) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 94 (0x5E) smb_vwv[ 7]= 60 (0x3C) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_bcc=99 [2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 00 00 80 4F 1D D9 F5 A4 CA 01 80 4F 1D ......O. ......O. [0010] D9 F5 A4 CA 01 00 59 AA FE F5 A4 CA 01 00 59 AA ......Y. ......Y. [0020] FE F5 A4 CA 01 80 00 00 00 00 00 00 00 00 00 00 ........ ........ [0030] 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 00 ........ ........ [0040] 00 00 00 00 00 00 00 00 00 16 00 00 00 5C 00 6A ........ .....\.j [0050] 00 6F 00 65 00 72 00 67 00 5C 00 74 00 65 00 73 .o.e.r.g .\.t.e.s [0060] 00 74 00 .t. [2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 138 [2010/02/03 18:31:59, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x8a [2010/02/03 18:31:59, 3] smbd/process.c:1459(process_smb) Transaction 8 of length 142 (0 toread) [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=138 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=9 smt_wct=15 smb_vwv[ 0]= 30 (0x1E) smb_vwv[ 1]= 40 (0x28) 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]= 30 (0x1E) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 40 (0x28) smb_vwv[12]= 98 (0x62) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 6 (0x6) smb_bcc=73 [2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 44 20 EC 03 00 00 00 00 5C 00 6A 00 6F 00 65 .D ..... .\.j.o.e [0010] 00 72 00 67 00 5C 00 74 00 65 00 73 00 74 00 00 .r.g.\.t .e.s.t.. [0020] 00 00 00 00 00 00 00 00 00 80 AA 31 A0 F6 A4 CA ........ ...1.... [0030] 01 80 F6 6C 9B F6 A4 CA 01 00 00 00 00 00 00 00 ...l.... ........ [0040] 00 00 00 00 00 00 00 00 00 ........ . [2010/02/03 18:31:59, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 18788) conn 0x89fd6b0 [2010/02/03 18:31:59, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/02/03 18:31:59, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "joerg/test" [2010/02/03 18:31:59, 5] smbd/filename.c:297(unix_convert) conversion finished joerg/test -> joerg/test [2010/02/03 18:31:59, 3] smbd/trans2.c:6899(call_trans2setfilepathinfo) call_trans2setfilepathinfo(6) joerg/test (fnum -1) info_level=1004 totdata=40 [2010/02/03 18:31:59, 6] smbd/trans2.c:5029(smb_set_file_dosmode) smb_set_file_dosmode: dosmode: 0x0 [2010/02/03 18:31:59, 10] smbd/trans2.c:5797(smb_set_file_basic_info) smb_set_file_basic_info: file joerg/test [2010/02/03 18:31:59, 5] smbd/trans2.c:4939(smb_set_file_time) smb_set_filetime: actime: Wed Feb 3 18:30:49 2010 smb_set_filetime: modtime: Wed Feb 3 18:30:41 2010 smb_set_file_time: createtime: Wed Feb 3 18:25:15 2010 smb_set_file_time: setting pending modtime to Wed Feb 3 18:30:41 2010 [2010/02/03 18:31:59, 5] locking/locking.c:1425(set_sticky_write_time) set_sticky_write_time: Mi 03 Feb 2010 18:30:41 CET CET id=305:34dad6:0 [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 0503000000000000D6DA [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x89f8fe0 [2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes) parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Do 01 Jan 1970 01:00:00 CET CET, tok: 0, num_share_modes: 1 [2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes) parse_share_modes: share_mode_entry[0]: pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0 [2010/02/03 18:31:59, 10] locking/locking.c:716(unparse_share_modes) unparse_share_modes: del: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num: 1 [2010/02/03 18:31:59, 10] locking/locking.c:518(print_share_mode_table) print_share_mode_table: share_mode_entry[0]: pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0 [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 0503000000000000D6DA [2010/02/03 18:31:59, 10] smbd/trans2.c:4989(smb_set_file_time) smb_set_file_time: setting utimes to modified values. [2010/02/03 18:31:59, 6] smbd/dosmode.c:721(file_ntimes) file_ntime: actime: Wed Feb 3 18:30:49 2010 [2010/02/03 18:31:59, 6] smbd/dosmode.c:723(file_ntimes) file_ntime: modtime: Wed Feb 3 18:30:41 2010 [2010/02/03 18:31:59, 6] smbd/dosmode.c:725(file_ntimes) file_ntime: createtime: Wed Feb 3 18:25:15 2010 [2010/02/03 18:31:59, 10] smbd/notify_internal.c:593(notify_trigger) notify_trigger called action=0x3, filter=0x30, path=/home/joerg/test [2010/02/03 18:31:59, 10] smbd/notify_internal.c:177(notify_load) notify_load: [2010/02/03 18:31:59, 1] ../librpc/ndr/ndr.c:214(ndr_print_debug) notify->array: struct notify_array num_depths : 0x00000000 (0) depth: ARRAY(0) [2010/02/03 18:31:59, 9] smbd/trans2.c:820(send_trans2_replies) t2_rep: params_sent_thistime = 2, data_sent_thistime = 0, useable_space = 131012 [2010/02/03 18:31:59, 9] smbd/trans2.c:822(send_trans2_replies) t2_rep: params_to_send = 2, data_to_send = 0, paramsize = 2, datasize = 0 [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=58 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=9 smt_wct=10 smb_vwv[ 0]= 2 (0x2) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 2 (0x2) smb_vwv[ 4]= 56 (0x38) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_bcc=3 [2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 ... [2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 98 [2010/02/03 18:31:59, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x62 [2010/02/03 18:31:59, 3] smbd/process.c:1459(process_smb) Transaction 9 of length 102 (0 toread) [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=98 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=10 smt_wct=15 smb_vwv[ 0]= 30 (0x1E) 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]= 30 (0x1E) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 98 (0x62) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=33 [2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 44 20 07 01 00 00 00 00 5C 00 6A 00 6F 00 65 .D ..... .\.j.o.e [0010] 00 72 00 67 00 5C 00 74 00 65 00 73 00 74 00 00 .r.g.\.t .e.s.t.. [0020] 00 . [2010/02/03 18:31:59, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 18788) conn 0x89fd6b0 [2010/02/03 18:31:59, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/02/03 18:31:59, 3] smbd/trans2.c:3955(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263 [2010/02/03 18:31:59, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "joerg/test" [2010/02/03 18:31:59, 5] smbd/filename.c:297(unix_convert) conversion finished joerg/test -> joerg/test [2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes) parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num_share_modes: 1 [2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes) parse_share_modes: share_mode_entry[0]: pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0 [2010/02/03 18:31:59, 3] smbd/trans2.c:4069(call_trans2qfilepathinfo) call_trans2qfilepathinfo joerg/test (fnum = -1) level=263 call=5 total_data=0 [2010/02/03 18:31:59, 8] smbd/dosmode.c:474(dos_mode) dos_mode: joerg/test [2010/02/03 18:31:59, 8] smbd/dosmode.c:175(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/02/03 18:31:59, 8] smbd/dosmode.c:521(dos_mode) dos_mode returning [2010/02/03 18:31:59, 10] smbd/trans2.c:204(get_ea_names_from_file) get_ea_list_from_file: ea_namelist size = 0 [2010/02/03 18:31:59, 10] smbd/trans2.c:4404(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_FILE_ALL_INFORMATION [2010/02/03 18:31:59, 9] smbd/trans2.c:820(send_trans2_replies) t2_rep: params_sent_thistime = 2, data_sent_thistime = 94, useable_space = 131010 [2010/02/03 18:31:59, 9] smbd/trans2.c:822(send_trans2_replies) t2_rep: params_to_send = 2, data_to_send = 94, paramsize = 2, datasize = 94 [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=154 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=10 smt_wct=10 smb_vwv[ 0]= 2 (0x2) smb_vwv[ 1]= 94 (0x5E) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 2 (0x2) smb_vwv[ 4]= 56 (0x38) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 94 (0x5E) smb_vwv[ 7]= 60 (0x3C) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_bcc=99 [2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 00 00 80 F6 6C 9B F6 A4 CA 01 80 AA 31 .......l .......1 [0010] A0 F6 A4 CA 01 80 F6 6C 9B F6 A4 CA 01 80 F6 6C .......l .......l [0020] 9B F6 A4 CA 01 80 00 00 00 00 00 00 00 00 00 00 ........ ........ [0030] 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 00 ........ ........ [0040] 00 00 00 00 00 00 00 00 00 16 00 00 00 5C 00 6A ........ .....\.j [0050] 00 6F 00 65 00 72 00 67 00 5C 00 74 00 65 00 73 .o.e.r.g .\.t.e.s [0060] 00 74 00 .t. [2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 72 [2010/02/03 18:31:59, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x48 [2010/02/03 18:31:59, 3] smbd/process.c:1459(process_smb) Transaction 10 of length 76 (0 toread) [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=72 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=11 smt_wct=15 smb_vwv[ 0]= 4 (0x4) 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]= 4 (0x4) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 72 (0x48) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 7 (0x7) smb_bcc=7 [2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 44 20 6B 12 07 01 .D k... [2010/02/03 18:31:59, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 18788) conn 0x89fd6b0 [2010/02/03 18:31:59, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/02/03 18:31:59, 3] smbd/trans2.c:3879(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 263 [2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes) parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num_share_modes: 1 [2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes) parse_share_modes: share_mode_entry[0]: pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0 [2010/02/03 18:31:59, 3] smbd/trans2.c:4069(call_trans2qfilepathinfo) call_trans2qfilepathinfo joerg/test (fnum = 4715) level=263 call=7 total_data=0 [2010/02/03 18:31:59, 8] smbd/dosmode.c:474(dos_mode) dos_mode: joerg/test [2010/02/03 18:31:59, 8] smbd/dosmode.c:175(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/02/03 18:31:59, 8] smbd/dosmode.c:521(dos_mode) dos_mode returning [2010/02/03 18:31:59, 10] smbd/trans2.c:204(get_ea_names_from_file) get_ea_list_from_file: ea_namelist size = 0 [2010/02/03 18:31:59, 10] smbd/trans2.c:4404(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_FILE_ALL_INFORMATION [2010/02/03 18:31:59, 9] smbd/trans2.c:820(send_trans2_replies) t2_rep: params_sent_thistime = 2, data_sent_thistime = 94, useable_space = 131010 [2010/02/03 18:31:59, 9] smbd/trans2.c:822(send_trans2_replies) t2_rep: params_to_send = 2, data_to_send = 94, paramsize = 2, datasize = 94 [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=154 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=11 smt_wct=10 smb_vwv[ 0]= 2 (0x2) smb_vwv[ 1]= 94 (0x5E) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 2 (0x2) smb_vwv[ 4]= 56 (0x38) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 94 (0x5E) smb_vwv[ 7]= 60 (0x3C) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_bcc=99 [2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 00 00 80 F6 6C 9B F6 A4 CA 01 80 AA 31 .......l .......1 [0010] A0 F6 A4 CA 01 80 F6 6C 9B F6 A4 CA 01 80 F6 6C .......l .......l [0020] 9B F6 A4 CA 01 80 00 00 00 00 00 00 00 00 00 00 ........ ........ [0030] 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 00 ........ ........ [0040] 00 00 00 00 00 00 00 00 00 16 00 00 00 5C 00 6A ........ .....\.j [0050] 00 6F 00 65 00 72 00 67 00 5C 00 74 00 65 00 73 .o.e.r.g .\.t.e.s [0060] 00 74 00 .t. [2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 76 [2010/02/03 18:31:59, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x4c [2010/02/03 18:31:59, 3] smbd/process.c:1459(process_smb) Transaction 11 of length 80 (0 toread) [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=76 smb_com=0x2f smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=12 smt_wct=14 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 4715 (0x126B) smb_vwv[ 3]= 0 (0x0) 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]= 0 (0x0) smb_vwv[10]= 12 (0xC) smb_vwv[11]= 64 (0x40) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 0 (0x0) smb_bcc=13 [2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 48 65 6C 6C 6F 20 77 6F 72 6C 64 0A .Hello w orld. [2010/02/03 18:31:59, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 18788) conn 0x89fd6b0 [2010/02/03 18:31:59, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 0503000000000000D6DA [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x8a040b0 [2010/02/03 18:31:59, 10] locking/brlock.c:1849(brl_get_locks_internal) brl_get_locks_internal: 0 current locks on file_id 305:34dad6:0 [2010/02/03 18:31:59, 10] locking/posix.c:284(is_posix_locked) is_posix_locked: File joerg/test, offset = 0, count = 12, type = WRITE [2010/02/03 18:31:59, 10] locking/posix.c:172(posix_lock_in_range) posix_lock_in_range: offset_out = 0, count_out = 12 [2010/02/03 18:31:59, 8] locking/posix.c:234(posix_fcntl_getlock) posix_fcntl_getlock 30 0 12 1 [2010/02/03 18:31:59, 8] lib/util.c:1832(fcntl_getlock) fcntl_getlock fd=30 offset=0 count=12 type=1 [2010/02/03 18:31:59, 3] lib/util.c:1856(fcntl_getlock) fcntl_getlock: fd 30 is returned info 2 pid 0 [2010/02/03 18:31:59, 8] locking/posix.c:264(posix_fcntl_getlock) posix_fcntl_getlock: Lock query call successful [2010/02/03 18:31:59, 10] locking/brlock.c:1270(brl_locktest) brl_locktest: posix start=0 len=12 unlocked for fnum 4715 file joerg/test [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 0503000000000000D6DA [2010/02/03 18:31:59, 10] locking/locking.c:152(strict_lock_default) strict_lock_default: flavour = WINDOWS_LOCK brl start=0 len=12 unlocked for fnum 4715 file joerg/test [2010/02/03 18:31:59, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "update_write_time_handler": 0x89fc4f8 [2010/02/03 18:31:59, 8] smbd/dosmode.c:474(dos_mode) dos_mode: joerg/test [2010/02/03 18:31:59, 8] smbd/dosmode.c:175(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/02/03 18:31:59, 8] smbd/dosmode.c:521(dos_mode) dos_mode returning [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 0503000000000000D6DA [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x8a04290 [2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes) parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num_share_modes: 1 [2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes) parse_share_modes: share_mode_entry[0]: pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0 [2010/02/03 18:31:59, 10] smbd/oplock.c:769(contend_level2_oplocks_begin_default) release_level_2_oplocks_on_change: num_share_modes = 1 [2010/02/03 18:31:59, 10] smbd/oplock.c:792(contend_level2_oplocks_begin_default) release_level_2_oplocks_on_change: share_entry[0]->op_type == 16 [2010/02/03 18:31:59, 3] smbd/oplock.c:369(break_level2_to_none_async) process_oplock_async_level2_break_message: downgrading fake level 2 oplock. [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 0503000000000000D6DA [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x8a04550 [2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes) parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num_share_modes: 1 [2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes) parse_share_modes: share_mode_entry[0]: pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0 [2010/02/03 18:31:59, 10] locking/locking.c:716(unparse_share_modes) unparse_share_modes: del: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num: 1 [2010/02/03 18:31:59, 10] locking/locking.c:518(print_share_mode_table) print_share_mode_table: share_mode_entry[0]: pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x0, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0 [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 0503000000000000D6DA [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 0503000000000000D6DA [2010/02/03 18:31:59, 10] smbd/fileio.c:138(real_write_file) real_write_file (joerg/test): pos = 0, size = 12, returned 12 [2010/02/03 18:31:59, 3] smbd/reply.c:4266(reply_write_and_X) writeX fnum=4715 num=12 wrote=12 [2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 98 [2010/02/03 18:31:59, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x62 [2010/02/03 18:31:59, 3] smbd/process.c:1459(process_smb) Transaction 12 of length 102 (0 toread) [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=98 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=13 smt_wct=15 smb_vwv[ 0]= 30 (0x1E) 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]= 30 (0x1E) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 98 (0x62) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=33 [2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 44 20 07 01 00 00 00 00 5C 00 6A 00 6F 00 65 .D ..... .\.j.o.e [0010] 00 72 00 67 00 5C 00 74 00 65 00 73 00 74 00 00 .r.g.\.t .e.s.t.. [0020] 00 . [2010/02/03 18:31:59, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 18788) conn 0x89fd6b0 [2010/02/03 18:31:59, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/02/03 18:31:59, 3] smbd/trans2.c:3955(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263 [2010/02/03 18:31:59, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "joerg/test" [2010/02/03 18:31:59, 5] smbd/filename.c:297(unix_convert) conversion finished joerg/test -> joerg/test [2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes) parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num_share_modes: 1 [2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes) parse_share_modes: share_mode_entry[0]: pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x0, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0 [2010/02/03 18:31:59, 3] smbd/trans2.c:4069(call_trans2qfilepathinfo) call_trans2qfilepathinfo joerg/test (fnum = -1) level=263 call=5 total_data=0 [2010/02/03 18:31:59, 8] smbd/dosmode.c:474(dos_mode) dos_mode: joerg/test [2010/02/03 18:31:59, 8] smbd/dosmode.c:175(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/02/03 18:31:59, 8] smbd/dosmode.c:521(dos_mode) dos_mode returning [2010/02/03 18:31:59, 10] smbd/trans2.c:204(get_ea_names_from_file) get_ea_list_from_file: ea_namelist size = 0 [2010/02/03 18:31:59, 10] smbd/trans2.c:4404(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_FILE_ALL_INFORMATION [2010/02/03 18:31:59, 9] smbd/trans2.c:820(send_trans2_replies) t2_rep: params_sent_thistime = 2, data_sent_thistime = 94, useable_space = 131010 [2010/02/03 18:31:59, 9] smbd/trans2.c:822(send_trans2_replies) t2_rep: params_to_send = 2, data_to_send = 94, paramsize = 2, datasize = 94 [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=154 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=13 smt_wct=10 smb_vwv[ 0]= 2 (0x2) smb_vwv[ 1]= 94 (0x5E) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 2 (0x2) smb_vwv[ 4]= 56 (0x38) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 94 (0x5E) smb_vwv[ 7]= 60 (0x3C) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_bcc=99 [2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 00 00 80 AA 31 A0 F6 A4 CA 01 80 AA 31 .......1 .......1 [0010] A0 F6 A4 CA 01 80 F6 6C 9B F6 A4 CA 01 80 F6 6C .......l .......l [0020] 9B F6 A4 CA 01 80 00 00 00 00 00 00 00 00 10 00 ........ ........ [0030] 00 00 00 00 00 0C 00 00 00 00 00 00 00 01 00 00 ........ ........ [0040] 00 00 00 00 00 00 00 00 00 16 00 00 00 5C 00 6A ........ .....\.j [0050] 00 6F 00 65 00 72 00 67 00 5C 00 74 00 65 00 73 .o.e.r.g .\.t.e.s [0060] 00 74 00 .t. [2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 72 [2010/02/03 18:31:59, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x48 [2010/02/03 18:31:59, 3] smbd/process.c:1459(process_smb) Transaction 13 of length 76 (0 toread) [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=72 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=14 smt_wct=15 smb_vwv[ 0]= 4 (0x4) 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]= 4 (0x4) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 72 (0x48) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 7 (0x7) smb_bcc=7 [2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 44 20 6B 12 07 01 .D k... [2010/02/03 18:31:59, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 18788) conn 0x89fd6b0 [2010/02/03 18:31:59, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/02/03 18:31:59, 3] smbd/trans2.c:3879(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 263 [2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes) parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num_share_modes: 1 [2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes) parse_share_modes: share_mode_entry[0]: pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x0, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0 [2010/02/03 18:31:59, 3] smbd/trans2.c:4069(call_trans2qfilepathinfo) call_trans2qfilepathinfo joerg/test (fnum = 4715) level=263 call=7 total_data=0 [2010/02/03 18:31:59, 8] smbd/dosmode.c:474(dos_mode) dos_mode: joerg/test [2010/02/03 18:31:59, 8] smbd/dosmode.c:175(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/02/03 18:31:59, 8] smbd/dosmode.c:521(dos_mode) dos_mode returning [2010/02/03 18:31:59, 10] smbd/trans2.c:204(get_ea_names_from_file) get_ea_list_from_file: ea_namelist size = 0 [2010/02/03 18:31:59, 10] smbd/trans2.c:4404(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_FILE_ALL_INFORMATION [2010/02/03 18:31:59, 9] smbd/trans2.c:820(send_trans2_replies) t2_rep: params_sent_thistime = 2, data_sent_thistime = 94, useable_space = 131010 [2010/02/03 18:31:59, 9] smbd/trans2.c:822(send_trans2_replies) t2_rep: params_to_send = 2, data_to_send = 94, paramsize = 2, datasize = 94 [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=154 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=14 smt_wct=10 smb_vwv[ 0]= 2 (0x2) smb_vwv[ 1]= 94 (0x5E) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 2 (0x2) smb_vwv[ 4]= 56 (0x38) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 94 (0x5E) smb_vwv[ 7]= 60 (0x3C) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_bcc=99 [2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 00 00 80 AA 31 A0 F6 A4 CA 01 80 AA 31 .......1 .......1 [0010] A0 F6 A4 CA 01 80 F6 6C 9B F6 A4 CA 01 80 F6 6C .......l .......l [0020] 9B F6 A4 CA 01 80 00 00 00 00 00 00 00 00 10 00 ........ ........ [0030] 00 00 00 00 00 0C 00 00 00 00 00 00 00 01 00 00 ........ ........ [0040] 00 00 00 00 00 00 00 00 00 16 00 00 00 5C 00 6A ........ .....\.j [0050] 00 6F 00 65 00 72 00 67 00 5C 00 74 00 65 00 73 .o.e.r.g .\.t.e.s [0060] 00 74 00 .t. [2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 41 [2010/02/03 18:31:59, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x29 [2010/02/03 18:31:59, 3] smbd/process.c:1459(process_smb) Transaction 14 of length 45 (0 toread) [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=41 smb_com=0x4 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=15 smt_wct=3 smb_vwv[ 0]= 4715 (0x126B) smb_vwv[ 1]=65535 (0xFFFF) smb_vwv[ 2]=65535 (0xFFFF) smb_bcc=0 [2010/02/03 18:31:59, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 18788) conn 0x89fd6b0 [2010/02/03 18:31:59, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/02/03 18:31:59, 3] smbd/reply.c:4483(reply_close) close fd=30 fnum=4715 (numopen=1) [2010/02/03 18:31:59, 6] smbd/close.c:454(set_close_write_time) close_write_time: Thu Jan 1 00:59:59 1970 [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 0503000000000000D6DA [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x8a040b0 [2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes) parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num_share_modes: 1 [2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes) parse_share_modes: share_mode_entry[0]: pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x0, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0 [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 0503000000000000D6DA [2010/02/03 18:31:59, 10] locking/posix.c:495(get_windows_lock_ref_count) get_windows_lock_count for file joerg/test = 0 [2010/02/03 18:31:59, 10] locking/posix.c:521(delete_windows_lock_ref_count) delete_windows_lock_ref_count for file joerg/test [2010/02/03 18:31:59, 5] smbd/trans2.c:4939(smb_set_file_time) smb_set_filetime: actime: Wed Feb 3 18:30:49 2010 smb_set_filetime: modtime: Wed Feb 3 18:32:00 2010 smb_set_file_time: createtime: Wed Feb 3 18:30:49 2010 smb_set_file_time: setting pending modtime to Wed Feb 3 18:32:00 2010 [2010/02/03 18:31:59, 10] lib/events.c:287(s3_event_debug) s3_event: Destroying timer event 0x89fc4f8 "update_write_time_handler" [2010/02/03 18:31:59, 5] locking/locking.c:1425(set_sticky_write_time) set_sticky_write_time: Mi 03 Feb 2010 18:32:00 CET CET id=305:34dad6:0 [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 0503000000000000D6DA [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x89fa188 [2010/02/03 18:31:59, 3] locking/locking.c:858(get_share_mode_lock) fill_share_mode_lock failed [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 0503000000000000D6DA [2010/02/03 18:31:59, 10] smbd/trans2.c:4989(smb_set_file_time) smb_set_file_time: setting utimes to modified values. [2010/02/03 18:31:59, 6] smbd/dosmode.c:721(file_ntimes) file_ntime: actime: Wed Feb 3 18:30:49 2010 [2010/02/03 18:31:59, 6] smbd/dosmode.c:723(file_ntimes) file_ntime: modtime: Wed Feb 3 18:32:00 2010 [2010/02/03 18:31:59, 6] smbd/dosmode.c:725(file_ntimes) file_ntime: createtime: Wed Feb 3 18:30:49 2010 [2010/02/03 18:31:59, 10] smbd/notify_internal.c:593(notify_trigger) notify_trigger called action=0x3, filter=0x10, path=/home/joerg/test [2010/02/03 18:31:59, 2] smbd/close.c:612(close_normal_file) joerg closed file joerg/test (numopen=0) NT_STATUS_OK [2010/02/03 18:31:59, 5] smbd/files.c:476(file_free) freed files structure 4715 (0 used) [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=35 smb_com=0x4 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=15 smt_wct=0 smb_bcc=0 [2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 98 [2010/02/03 18:31:59, 6] smbd/process.c:1456(process_smb) got message type 0x0 of len 0x62 [2010/02/03 18:31:59, 3] smbd/process.c:1459(process_smb) Transaction 15 of length 102 (0 toread) [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=98 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=16 smt_wct=15 smb_vwv[ 0]= 30 (0x1E) 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]= 30 (0x1E) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 98 (0x62) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=33 [2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 44 20 07 01 00 00 00 00 5C 00 6A 00 6F 00 65 .D ..... .\.j.o.e [0010] 00 72 00 67 00 5C 00 74 00 65 00 73 00 74 00 00 .r.g.\.t .e.s.t.. [0020] 00 . [2010/02/03 18:31:59, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 18788) conn 0x89fd6b0 [2010/02/03 18:31:59, 4] smbd/uid.c:256(change_to_user) change_to_user: Skipping user change - already user [2010/02/03 18:31:59, 3] smbd/trans2.c:3955(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263 [2010/02/03 18:31:59, 5] smbd/filename.c:148(unix_convert) unix_convert called on file "joerg/test" [2010/02/03 18:31:59, 5] smbd/filename.c:297(unix_convert) conversion finished joerg/test -> joerg/test [2010/02/03 18:31:59, 10] locking/locking.c:891(fetch_share_mode_unlocked) fetch_share_mode_unlocked: no share_mode record around (file not open) [2010/02/03 18:31:59, 3] smbd/trans2.c:4069(call_trans2qfilepathinfo) call_trans2qfilepathinfo joerg/test (fnum = -1) level=263 call=5 total_data=0 [2010/02/03 18:31:59, 8] smbd/dosmode.c:474(dos_mode) dos_mode: joerg/test [2010/02/03 18:31:59, 8] smbd/dosmode.c:175(dos_mode_from_sbuf) dos_mode_from_sbuf returning [2010/02/03 18:31:59, 8] smbd/dosmode.c:521(dos_mode) dos_mode returning [2010/02/03 18:31:59, 10] smbd/trans2.c:204(get_ea_names_from_file) get_ea_list_from_file: ea_namelist size = 0 [2010/02/03 18:31:59, 10] smbd/trans2.c:4404(call_trans2qfilepathinfo) call_trans2qfilepathinfo: SMB_FILE_ALL_INFORMATION [2010/02/03 18:31:59, 9] smbd/trans2.c:820(send_trans2_replies) t2_rep: params_sent_thistime = 2, data_sent_thistime = 94, useable_space = 131010 [2010/02/03 18:31:59, 9] smbd/trans2.c:822(send_trans2_replies) t2_rep: params_to_send = 2, data_to_send = 94, paramsize = 2, datasize = 94 [2010/02/03 18:31:59, 5] lib/util.c:632(show_msg) [2010/02/03 18:31:59, 5] lib/util.c:642(show_msg) size=154 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=51201 smb_tid=1 smb_pid=8542 smb_uid=100 smb_mid=16 smt_wct=10 smb_vwv[ 0]= 2 (0x2) smb_vwv[ 1]= 94 (0x5E) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 2 (0x2) smb_vwv[ 4]= 56 (0x38) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 94 (0x5E) smb_vwv[ 7]= 60 (0x3C) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_bcc=99 [2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data) [0000] 00 00 00 00 00 80 AA 31 A0 F6 A4 CA 01 80 AA 31 .......1 .......1 [0010] A0 F6 A4 CA 01 00 68 83 CA F6 A4 CA 01 00 68 83 ......h. ......h. [0020] CA F6 A4 CA 01 80 00 00 00 00 00 00 00 00 10 00 ........ ........ [0030] 00 00 00 00 00 0C 00 00 00 00 00 00 00 01 00 00 ........ ........ [0040] 00 00 00 00 00 00 00 00 00 16 00 00 00 5C 00 6A ........ .....\.j [0050] 00 6F 00 65 00 72 00 67 00 5C 00 74 00 65 00 73 .o.e.r.g .\.t.e.s [0060] 00 74 00 .t. [2010/02/03 18:31:59, 5] lib/util_sock.c:528(read_fd_with_timeout) read_fd_with_timeout: blocking read. EOF from client. [2010/02/03 18:31:59, 10] smbd/process.c:271(receive_smb_raw_talloc) receive_smb_raw: NT_STATUS_END_OF_FILE [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/02/03 18:31:59, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2010/02/03 18:31:59, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2010/02/03 18:31:59, 5] smbd/uid.c:368(change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/02/03 18:31:59, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2010/02/03 18:31:59, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2010/02/03 18:31:59, 5] smbd/uid.c:368(change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2010/02/03 18:31:59, 1] smbd/service.c:1226(close_cnum) joerg (::ffff:10.10.10.26) closed connection to service all-homes [2010/02/03 18:31:59, 3] smbd/connection.c:31(yield_connection) Yielding connection to all-homes [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 64490000FFFFFFFF0100 [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x89f9138 [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 64490000FFFFFFFF0100 [2010/02/03 18:31:59, 4] smbd/vfs.c:753(vfs_ChDir) vfs_ChDir to / [2010/02/03 18:31:59, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/02/03 18:31:59, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2010/02/03 18:31:59, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2010/02/03 18:31:59, 5] smbd/uid.c:368(change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2010/02/03 18:31:59, 5] lib/messages.c:329(messaging_deregister) Deregistering messaging pointer for type 784 - private_data=0x89f20e0 [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 49442F31383738382F31 [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x89fd6b0 [2010/02/03 18:31:59, 4] auth/pampass.c:472(smb_pam_start) smb_pam_start: PAM: Init user: joerg [2010/02/03 18:31:59, 4] auth/pampass.c:489(smb_pam_start) smb_pam_start: PAM: setting rhost to: ::ffff:10.10.10.26 [2010/02/03 18:31:59, 4] auth/pampass.c:498(smb_pam_start) smb_pam_start: PAM: setting tty [2010/02/03 18:31:59, 4] auth/pampass.c:506(smb_pam_start) smb_pam_start: PAM: Init passed for user: joerg [2010/02/03 18:31:59, 4] auth/pampass.c:643(smb_internal_pam_session) smb_internal_pam_session: PAM: tty set to: smb/18788/100 [2010/02/03 18:31:59, 4] auth/pampass.c:450(smb_pam_end) smb_pam_end: PAM: PAM_END OK. [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 49442F31383738382F31 [2010/02/03 18:31:59, 3] smbd/connection.c:31(yield_connection) Yielding connection to [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 64490000FFFFFFFFFFFF [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x89f9138 [2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 64490000FFFFFFFFFFFF [2010/02/03 18:31:59, 3] smbd/server.c:849(exit_server_common) Server exit (failed to receive smb request)