[2003/06/15 23:28:16, 6] param/loadparm.c:lp_file_list_changed(2617) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Sun Jun 15 23:28:00 2003 [2003/06/15 23:28:16, 5] smbd/connection.c:claim_connection(170) claiming 0 [2003/06/15 23:28:16, 5] smbd/reply.c:reply_special(136) init msg_type=0x81 msg_flags=0x0 [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,4) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,4) wrote 4 [2003/06/15 23:28:16, 10] lib/util_sock.c:read_smb_length_return_keepalive(463) got smb length of 154 [2003/06/15 23:28:16, 6] smbd/process.c:process_smb(881) got message type 0x0 of len 0x9a [2003/06/15 23:28:16, 3] smbd/process.c:process_smb(882) Transaction 1 of length 158 [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=154 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=0 smb_tid=1 smb_pid=6411 smb_uid=0 smb_mid=36225 smt_wct=0 smb_bcc=119 [2003/06/15 23:28:16, 10] lib/util.c:dump_data(1887) [000] 02 50 43 20 4E 45 54 57 4F 52 4B 20 50 52 4F 47 .PC NETW ORK PROG [010] 52 41 4D 20 31 2E 30 00 02 4D 49 43 52 4F 53 4F RAM 1.0. .MICROSO [020] 46 54 20 4E 45 54 57 4F 52 4B 53 20 33 2E 30 00 FT NETWO RKS 3.0. [030] 02 44 4F 53 20 4C 4D 31 2E 32 58 30 30 32 00 02 .DOS LM1 .2X002.. [040] 44 4F 53 20 4C 41 4E 4D 41 4E 32 2E 31 00 02 57 DOS LANM AN2.1..W [050] 69 6E 64 6F 77 73 20 66 6F 72 20 57 6F 72 6B 67 indows f or Workg [060] 72 6F 75 70 73 20 33 2E 31 61 00 02 4E 54 20 4C roups 3. 1a..NT L [070] 4D 20 30 2E 31 32 00 M 0.12. [2003/06/15 23:28:16, 3] smbd/process.c:switch_message(676) switch message SMBnegprot (pid 16986) [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(490) NT user token: (NULL) [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_root_user(218) change_to_root_user: now uid=(0,0) gid=(0,0) [2003/06/15 23:28:16, 3] smbd/negprot.c:reply_negprot(439) Requested protocol [PC NETWORK PROGRAM 1.0] [2003/06/15 23:28:16, 3] smbd/negprot.c:reply_negprot(439) Requested protocol [MICROSOFT NETWORKS 3.0] [2003/06/15 23:28:16, 3] smbd/negprot.c:reply_negprot(439) Requested protocol [DOS LM1.2X002] [2003/06/15 23:28:16, 3] smbd/negprot.c:reply_negprot(439) Requested protocol [DOS LANMAN2.1] [2003/06/15 23:28:16, 3] smbd/negprot.c:reply_negprot(439) Requested protocol [Windows for Workgroups 3.1a] [2003/06/15 23:28:16, 3] smbd/negprot.c:reply_negprot(439) Requested protocol [NT LM 0.12] [2003/06/15 23:28:16, 6] param/loadparm.c:lp_file_list_changed(2617) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Sun Jun 15 23:28:00 2003 [2003/06/15 23:28:16, 6] param/loadparm.c:lp_file_list_changed(2617) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Sun Jun 15 23:28:00 2003 [2003/06/15 23:28:16, 10] smbd/negprot.c:get_challenge(40) get challenge: creating negprot_global_auth_context [2003/06/15 23:28:16, 5] auth/auth.c:make_auth_context_subsystem(448) Making default auth method list for security=user, encrypt passwords = yes [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(45) Attempting to register auth backend rhosts [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(57) Successfully added auth method 'rhosts' [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(45) Attempting to register auth backend hostsequiv [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(57) Successfully added auth method 'hostsequiv' [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(45) Attempting to register auth backend samstrict_dc [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(57) Successfully added auth method 'samstrict_dc' [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(45) Attempting to register auth backend samstrict [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(57) Successfully added auth method 'samstrict' [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(45) Attempting to register auth backend sam [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(57) Successfully added auth method 'sam' [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(45) Attempting to register auth backend unix [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(57) Successfully added auth method 'unix' [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(45) Attempting to register auth backend winbind [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(57) Successfully added auth method 'winbind' [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(45) Attempting to register auth backend smbserver [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(57) Successfully added auth method 'smbserver' [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(45) Attempting to register auth backend trustdomain [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(57) Successfully added auth method 'trustdomain' [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(45) Attempting to register auth backend ntdomain [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(57) Successfully added auth method 'ntdomain' [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(45) Attempting to register auth backend guest [2003/06/15 23:28:16, 5] auth/auth.c:smb_register_auth(57) Successfully added auth method 'guest' [2003/06/15 23:28:16, 5] auth/auth.c:load_auth_module(356) load_auth_module: Attempting to find an auth method to match guest [2003/06/15 23:28:16, 5] auth/auth.c:load_auth_module(381) load_auth_module: auth method guest has a valid init [2003/06/15 23:28:16, 5] auth/auth.c:load_auth_module(356) load_auth_module: Attempting to find an auth method to match sam [2003/06/15 23:28:16, 5] auth/auth.c:load_auth_module(381) load_auth_module: auth method sam has a valid init [2003/06/15 23:28:16, 10] smbd/negprot.c:get_challenge(45) get challenge: getting challenge [2003/06/15 23:28:16, 5] auth/auth.c:get_ntlm_challenge(93) auth_get_challenge: module guest did not want to specify a challenge [2003/06/15 23:28:16, 5] auth/auth.c:get_ntlm_challenge(93) auth_get_challenge: module sam did not want to specify a challenge [2003/06/15 23:28:16, 5] auth/auth.c:get_ntlm_challenge(132) auth_context challenge created by random [2003/06/15 23:28:16, 5] auth/auth.c:get_ntlm_challenge(133) challenge is: [2003/06/15 23:28:16, 5] lib/util.c:dump_data(1887) [000] 88 F5 33 66 CB E6 78 F9 .õ3fËæxù [2003/06/15 23:28:16, 3] smbd/negprot.c:reply_nt1(307) not using SPNEGO [2003/06/15 23:28:16, 3] smbd/negprot.c:reply_negprot(516) Selected protocol NT LM 0.12 [2003/06/15 23:28:16, 5] smbd/negprot.c:reply_negprot(522) negprot index=5 [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=95 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=18433 smb_tid=1 smb_pid=6411 smb_uid=0 smb_mid=36225 smt_wct=17 smb_vwv[ 0]= 5 (0x5) smb_vwv[ 1]=12803 (0x3203) smb_vwv[ 2]= 256 (0x100) smb_vwv[ 3]= 1024 (0x400) smb_vwv[ 4]= 65 (0x41) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 256 (0x100) smb_vwv[ 7]=23040 (0x5A00) smb_vwv[ 8]= 66 (0x42) smb_vwv[ 9]=64768 (0xFD00) smb_vwv[10]= 227 (0xE3) smb_vwv[11]= 0 (0x0) smb_vwv[12]=30936 (0x78D8) smb_vwv[13]=51222 (0xC816) smb_vwv[14]=49971 (0xC333) smb_vwv[15]=26625 (0x6801) smb_vwv[16]= 2049 (0x801) smb_bcc=26 [2003/06/15 23:28:16, 10] lib/util.c:dump_data(1887) [000] 88 F5 33 66 CB E6 78 F9 4D 00 49 00 44 00 45 00 .õ3fËæxù M.I.D.E. [010] 41 00 52 00 54 00 48 00 00 00 A.R.T.H. .. [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,99) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,99) wrote 99 [2003/06/15 23:28:16, 10] lib/util_sock.c:read_smb_length_return_keepalive(463) got smb length of 149 [2003/06/15 23:28:16, 6] smbd/process.c:process_smb(881) got message type 0x0 of len 0x95 [2003/06/15 23:28:16, 3] smbd/process.c:process_smb(882) Transaction 2 of length 153 [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=149 smb_com=0x73 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=16 smb_flg2=0 smb_tid=1 smb_pid=6411 smb_uid=1 smb_mid=36225 smt_wct=13 smb_vwv[ 0]= 117 (0x75) smb_vwv[ 1]= 122 (0x7A) smb_vwv[ 2]= 2920 (0xB68) smb_vwv[ 3]= 50 (0x32) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]=16986 (0x425A) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 24 (0x18) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 5 (0x5) smb_vwv[12]= 0 (0x0) smb_bcc=61 [2003/06/15 23:28:16, 10] lib/util.c:dump_data(1887) [000] 67 29 F8 29 ED 3C C9 66 A1 A2 3A B5 7E 7C E0 05 g)ø)í<Éf ¡¢:µ~|à. [010] A2 BC 78 C4 5A A7 AE EF 4A 48 54 00 4D 49 44 45 ¢¼xÄZ§®ï JHT.MIDE [020] 41 52 54 48 00 57 69 6E 64 6F 77 73 20 34 2E 30 ARTH.Win dows 4.0 [030] 00 57 69 6E 64 6F 77 73 20 34 2E 30 00 .Windows 4.0. [2003/06/15 23:28:16, 3] smbd/process.c:switch_message(676) switch message SMBsesssetupX (pid 16986) [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(490) NT user token: (NULL) [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_root_user(218) change_to_root_user: now uid=(0,0) gid=(0,0) [2003/06/15 23:28:16, 3] smbd/sesssetup.c:reply_sesssetup_and_X(547) wct=13 flg2=0x0 [2003/06/15 23:28:16, 3] smbd/sesssetup.c:reply_sesssetup_and_X(654) Domain=[MIDEARTH] NativeOS=[Windows 4.0] NativeLanMan=[Windows 4.0] [2003/06/15 23:28:16, 2] smbd/sesssetup.c:setup_new_vc_session(504) setup_new_vc_session: New VC == 0, if NT4.x compatible we would close all old resources. [2003/06/15 23:28:16, 3] smbd/sesssetup.c:reply_sesssetup_and_X(665) sesssetupX:name=[MIDEARTH]\[JHT]@[maggot] [2003/06/15 23:28:16, 6] param/loadparm.c:lp_file_list_changed(2617) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Sun Jun 15 23:28:00 2003 [2003/06/15 23:28:16, 4] lib/username.c:map_username(132) Scanning username map /etc/samba/smbusers [2003/06/15 23:28:16, 10] lib/username.c:user_in_list(486) user_in_list: checking user JHT in list [2003/06/15 23:28:16, 10] lib/username.c:user_in_list(490) user_in_list: checking user |JHT| against |administrator| [2003/06/15 23:28:16, 5] auth/auth_util.c:make_user_info_map(205) make_user_info_map: Mapping user [MIDEARTH]\[JHT] from workstation [maggot] [2003/06/15 23:28:16, 5] passdb/secrets.c:secrets_fetch_trusted_domain_password(300) secrets_fetch failed! [2003/06/15 23:28:16, 10] lib/gencache.c:gencache_get(285) Cache entry with key = TDOM/MIDEARTH couldn't be found [2003/06/15 23:28:16, 5] libsmb/trustdom_cache.c:trustdom_cache_fetch(171) no entry for trusted domain MIDEARTH found. [2003/06/15 23:28:16, 5] auth/auth_util.c:make_user_info(121) attempting to make a user_info for JHT (JHT) [2003/06/15 23:28:16, 5] auth/auth_util.c:make_user_info(131) making strings for JHT's user_info struct [2003/06/15 23:28:16, 5] auth/auth_util.c:make_user_info(173) making blobs for JHT's user_info struct [2003/06/15 23:28:16, 10] auth/auth_util.c:make_user_info(182) made an encrypted user_info for JHT (JHT) [2003/06/15 23:28:16, 3] auth/auth.c:check_ntlm_password(216) check_ntlm_password: Checking password for unmapped user [MIDEARTH]\[JHT]@[maggot] with the new password interface [2003/06/15 23:28:16, 3] auth/auth.c:check_ntlm_password(219) check_ntlm_password: mapped user is: [MIDEARTH]\[JHT]@[maggot] [2003/06/15 23:28:16, 10] auth/auth.c:check_ntlm_password(228) check_ntlm_password: auth_context challenge created by random [2003/06/15 23:28:16, 10] auth/auth.c:check_ntlm_password(230) challenge is: [2003/06/15 23:28:16, 5] lib/util.c:dump_data(1887) [000] 88 F5 33 66 CB E6 78 F9 .õ3fËæxù [2003/06/15 23:28:16, 5] auth/auth.c:check_ntlm_password(256) check_ntlm_password: guest authentication for user [JHT] FAILED with error NT_STATUS_LOGON_FAILURE [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:push_sec_ctx(256) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2003/06/15 23:28:16, 3] smbd/uid.c:push_conn_ctx(287) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(490) NT user token: (NULL) [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 5 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 6 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 7 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 8 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 9 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 20 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_username(577) pdb_set_username: setting username jht, was [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 11 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_domain(604) pdb_set_domain: setting domain MIDEARTH, was [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 13 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_nt_username(631) pdb_set_nt_username: setting nt username , was [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 14 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_fullname(658) pdb_set_full_name: setting full name John H Terpstra, was [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 12 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_homedir(766) pdb_set_homedir: setting home dir \\frodo\jht, was [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(739) pdb_set_dir_drive: setting dir drive H:, was NULL [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_logon_script(685) pdb_set_logon_script: setting logon script scripts\logon.bat, was [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_profile_path(712) pdb_set_profile_path: setting profile path \\frodo\Profiles\jht, was [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 22 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_workstations(845) pdb_set_workstations: setting workstations , was [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 23 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 25 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 30 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 31 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_user_sid(504) pdb_set_user_sid: setting user sid S-1-5-21-1593769616-160655940-3590153233-2000 [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 17 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(73) pdb_set_user_sid_from_rid: setting user sid S-1-5-21-1593769616-160655940-3590153233-2000 from rid 2000 [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_group_sid(540) pdb_set_group_sid: setting group sid S-1-5-21-1593769616-160655940-3590153233-513 [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 18 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_compat.c:pdb_set_group_sid_from_rid(100) pdb_set_group_sid_from_rid: setting group sid S-1-5-21-1593769616-160655940-3590153233-513 from rid 513 [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 27 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 15 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 28 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 29 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 19 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 16 -> now SET [2003/06/15 23:28:16, 10] passdb/pdb_get_set.c:pdb_set_init_flags(477) element 26 -> now SET [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:pop_sec_ctx(386) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 4] auth/auth_sam.c:sam_password_ok(246) sam_password_ok: Checking LM password [2003/06/15 23:28:16, 4] auth/auth_sam.c:sam_account_ok(323) sam_account_ok: Checking SMB password for user jht [2003/06/15 23:28:16, 10] sam/idmap_util.c:sid_to_uid(219) sid_to_uid: sid = [S-1-5-21-1593769616-160655940-3590153233-2000] [2003/06/15 23:28:16, 10] sam/idmap_util.c:sid_to_uid(245) sid_to_uid: Fall back to algorithmic mapping [2003/06/15 23:28:16, 10] sam/idmap_util.c:sid_to_uid(252) sid_to_uid: mapping: S-1-5-21-1593769616-160655940-3590153233-2000 -> 500 [2003/06/15 23:28:16, 10] sam/idmap_util.c:sid_to_gid(277) sid_to_gid: sid = [S-1-5-21-1593769616-160655940-3590153233-513] [2003/06/15 23:28:16, 10] sam/idmap_util.c:sid_to_gid(302) sid_to_gid: Fall back to algorithmic mapping [2003/06/15 23:28:16, 10] sam/idmap_util.c:sid_to_gid(309) sid_to_gid: mapping: S-1-5-21-1593769616-160655940-3590153233-513 -> 2147483404 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 500 Primary group is 2147483404 and contains 13 supplementary groups Group[ 0]: 2147483404 Group[ 1]: 5 Group[ 2]: 6 Group[ 3]: 7 Group[ 4]: 14 Group[ 5]: 16 Group[ 6]: 17 Group[ 7]: 19 Group[ 8]: 20 Group[ 9]: 22 Group[ 10]: 33 Group[ 11]: 71 Group[ 12]: 73 [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(181) gid_to_sid: gid = [2147483404] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(190) gid_to_sid: Failed to map gid = [2147483404] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(195) gid_to_sid: Fall back to algorithmic mapping: 2147483404 -> S-1-5-21-1593769616-160655940-3590153233-513 [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(181) gid_to_sid: gid = [5] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(190) gid_to_sid: Failed to map gid = [5] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(195) gid_to_sid: Fall back to algorithmic mapping: 5 -> S-1-5-21-1593769616-160655940-3590153233-1011 [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(181) gid_to_sid: gid = [6] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(190) gid_to_sid: Failed to map gid = [6] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(195) gid_to_sid: Fall back to algorithmic mapping: 6 -> S-1-5-21-1593769616-160655940-3590153233-1013 [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(181) gid_to_sid: gid = [7] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(181) gid_to_sid: gid = [14] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(190) gid_to_sid: Failed to map gid = [14] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(195) gid_to_sid: Fall back to algorithmic mapping: 14 -> S-1-5-21-1593769616-160655940-3590153233-1029 [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(181) gid_to_sid: gid = [16] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(190) gid_to_sid: Failed to map gid = [16] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(195) gid_to_sid: Fall back to algorithmic mapping: 16 -> S-1-5-21-1593769616-160655940-3590153233-1033 [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(181) gid_to_sid: gid = [17] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(190) gid_to_sid: Failed to map gid = [17] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(195) gid_to_sid: Fall back to algorithmic mapping: 17 -> S-1-5-21-1593769616-160655940-3590153233-1035 [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(181) gid_to_sid: gid = [19] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(190) gid_to_sid: Failed to map gid = [19] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(195) gid_to_sid: Fall back to algorithmic mapping: 19 -> S-1-5-21-1593769616-160655940-3590153233-1039 [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(181) gid_to_sid: gid = [20] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(190) gid_to_sid: Failed to map gid = [20] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(195) gid_to_sid: Fall back to algorithmic mapping: 20 -> S-1-5-21-1593769616-160655940-3590153233-1041 [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(181) gid_to_sid: gid = [22] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(190) gid_to_sid: Failed to map gid = [22] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(195) gid_to_sid: Fall back to algorithmic mapping: 22 -> S-1-5-21-1593769616-160655940-3590153233-1045 [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(181) gid_to_sid: gid = [33] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(190) gid_to_sid: Failed to map gid = [33] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(195) gid_to_sid: Fall back to algorithmic mapping: 33 -> S-1-5-21-1593769616-160655940-3590153233-1067 [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(181) gid_to_sid: gid = [71] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(181) gid_to_sid: gid = [73] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(190) gid_to_sid: Failed to map gid = [73] [2003/06/15 23:28:16, 10] sam/idmap_util.c:gid_to_sid(195) gid_to_sid: Fall back to algorithmic mapping: 73 -> S-1-5-21-1593769616-160655940-3590153233-1147 [2003/06/15 23:28:16, 10] auth/auth_util.c:debug_nt_user_token(495) NT user token of user S-1-5-21-1593769616-160655940-3590153233-2000 contains 17 SIDs SID[ 0]: S-1-5-21-1593769616-160655940-3590153233-2000 SID[ 1]: S-1-5-21-1593769616-160655940-3590153233-513 SID[ 2]: S-1-1-0 SID[ 3]: S-1-5-2 SID[ 4]: S-1-5-11 SID[ 5]: S-1-5-21-1593769616-160655940-3590153233-1011 SID[ 6]: S-1-5-21-1593769616-160655940-3590153233-1013 SID[ 7]: S-1-5-32-550 SID[ 8]: S-1-5-21-1593769616-160655940-3590153233-1029 SID[ 9]: S-1-5-21-1593769616-160655940-3590153233-1033 SID[ 10]: S-1-5-21-1593769616-160655940-3590153233-1035 SID[ 11]: S-1-5-21-1593769616-160655940-3590153233-1039 SID[ 12]: S-1-5-21-1593769616-160655940-3590153233-1041 SID[ 13]: S-1-5-21-1593769616-160655940-3590153233-1045 SID[ 14]: S-1-5-21-1593769616-160655940-3590153233-1067 SID[ 15]: S-1-5-21-1593769616-160655940-3590153233-512 SID[ 16]: S-1-5-21-1593769616-160655940-3590153233-1147 [2003/06/15 23:28:16, 5] auth/auth_util.c:make_server_info_sam(800) make_server_info_sam: made server info for user jht [2003/06/15 23:28:16, 3] auth/auth.c:check_ntlm_password(253) check_ntlm_password: sam authentication for user [JHT] suceeded [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:push_sec_ctx(256) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2003/06/15 23:28:16, 3] smbd/uid.c:push_conn_ctx(287) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(490) NT user token: (NULL) [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:pop_sec_ctx(386) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth.c:check_ntlm_password(275) check_ntlm_password: PAM Account for user [jht] suceeded [2003/06/15 23:28:16, 2] auth/auth.c:check_ntlm_password(288) check_ntlm_password: authentication for user [JHT] -> [JHT] -> [jht] suceeded [2003/06/15 23:28:16, 5] auth/auth_util.c:free_user_info(1143) attempting to free (and zero) a user_info structure [2003/06/15 23:28:16, 10] auth/auth_util.c:free_user_info(1146) structure was created for JHT [2003/06/15 23:28:16, 10] smbd/password.c:register_vuid(138) register_vuid: allocated vuid = 100 [2003/06/15 23:28:16, 10] smbd/password.c:register_vuid(199) register_vuid: (500,2147483404) jht JHT MIDEARTH guest=0 [2003/06/15 23:28:16, 3] smbd/password.c:register_vuid(201) User name: jht Real name: John H Terpstra [2003/06/15 23:28:16, 3] smbd/password.c:register_vuid(219) UNIX uid 500 is UNIX user jht, and will be vuid 100 [2003/06/15 23:28:16, 2] smbd/utmp.c:sys_utmp_update(413) utmp_update: uname:/var/run/utmp wname:/var/log/wtmp [2003/06/15 23:28:16, 3] smbd/password.c:register_vuid(235) Adding/updating homes service for user 'jht' using home directory: '/home/jht' [2003/06/15 23:28:16, 3] param/loadparm.c:lp_add_home(2273) adding home's share [jht] for user 'jht' at '/home/jht' [2003/06/15 23:28:16, 3] smbd/process.c:chain_reply(1016) Chained message [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=149 smb_com=0x75 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=16 smb_flg2=0 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=36225 smt_wct=4 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 2 (0x2) smb_vwv[ 3]= 1 (0x1) smb_bcc=16 [2003/06/15 23:28:16, 10] lib/util.c:dump_data(1887) [000] 00 5C 5C 46 52 4F 44 4F 5C 4A 48 54 00 41 3A 00 .\\FRODO \JHT.A:. [2003/06/15 23:28:16, 3] smbd/process.c:switch_message(676) switch message SMBtconX (pid 16986) [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(490) NT user token: (NULL) [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_root_user(218) change_to_root_user: now uid=(0,0) gid=(0,0) [2003/06/15 23:28:16, 4] smbd/reply.c:reply_tcon_and_X(260) Client requested device type [A:] for share [JHT] [2003/06/15 23:28:16, 5] smbd/service.c:make_connection(821) making a connection to 'homes' service [JHT] created at session setup time [2003/06/15 23:28:16, 3] lib/access.c:check_access(314) check_access: no hostnames in host allow/deny list. [2003/06/15 23:28:16, 2] lib/access.c:check_access(325) Allowed connection from (192.168.1.252) [2003/06/15 23:28:16, 10] lib/username.c:user_in_list(486) user_in_list: checking user jht in list [2003/06/15 23:28:16, 10] lib/username.c:user_in_list(490) user_in_list: checking user |jht| against |jht| [2003/06/15 23:28:16, 5] lib/username.c:Get_Pwnam(288) Finding user jht [2003/06/15 23:28:16, 5] lib/username.c:Get_Pwnam_internals(223) Trying _Get_Pwnam(), username as lowercase is jht [2003/06/15 23:28:16, 5] lib/username.c:Get_Pwnam_internals(251) Get_Pwnam_internals did find user [jht]! [2003/06/15 23:28:16, 3] smbd/service.c:make_connection_snum(534) Connect path is '/home/jht' for service [jht] [2003/06/15 23:28:16, 4] rpc_server/srv_srvsvc_nt.c:get_share_security(217) get_share_security: using default secdesc for jht [2003/06/15 23:28:16, 10] lib/util_seaccess.c:se_map_generic(192) se_map_generic(): mapped mask 0x10000000 to 0x001f01ff [2003/06/15 23:28:16, 10] lib/util_seaccess.c:se_access_check(250) se_access_check: requested access 0x00000002, for NT token with 17 entries and first sid S-1-5-21-1593769616-160655940-3590153233-2000. [2003/06/15 23:28:16, 3] lib/util_seaccess.c:se_access_check(267) [2003/06/15 23:28:16, 3] lib/util_seaccess.c:se_access_check(268) se_access_check: user sid is S-1-5-21-1593769616-160655940-3590153233-2000 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-513 se_access_check: also S-1-1-0 se_access_check: also S-1-5-2 se_access_check: also S-1-5-11 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1011 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1013 se_access_check: also S-1-5-32-550 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1029 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1033 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1035 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1039 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1041 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1045 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1067 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-512 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1147 se_access_check: ACE 0: type 0, flags = 0x00, SID = S-1-1-0 mask = 101f01ff, current desired = 2 [2003/06/15 23:28:16, 5] lib/util_seaccess.c:se_access_check(325) se_access_check: access (2) granted. [2003/06/15 23:28:16, 3] smbd/vfs.c:vfs_init_default(201) Initialising default vfs hooks [2003/06/15 23:28:16, 5] smbd/connection.c:claim_connection(170) claiming jht 0 [2003/06/15 23:28:16, 10] lib/username.c:user_in_list(486) user_in_list: checking user jht in list [2003/06/15 23:28:16, 10] lib/username.c:user_in_list(490) user_in_list: checking user |jht| against |jht| [2003/06/15 23:28:16, 4] rpc_server/srv_srvsvc_nt.c:get_share_security(217) get_share_security: using default secdesc for jht [2003/06/15 23:28:16, 10] lib/util_seaccess.c:se_map_generic(192) se_map_generic(): mapped mask 0x10000000 to 0x001f01ff [2003/06/15 23:28:16, 10] lib/util_seaccess.c:se_access_check(250) se_access_check: requested access 0x00000002, for NT token with 17 entries and first sid S-1-5-21-1593769616-160655940-3590153233-2000. [2003/06/15 23:28:16, 3] lib/util_seaccess.c:se_access_check(267) [2003/06/15 23:28:16, 3] lib/util_seaccess.c:se_access_check(268) se_access_check: user sid is S-1-5-21-1593769616-160655940-3590153233-2000 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-513 se_access_check: also S-1-1-0 se_access_check: also S-1-5-2 se_access_check: also S-1-5-11 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1011 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1013 se_access_check: also S-1-5-32-550 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1029 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1033 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1035 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1039 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1041 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1045 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1067 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-512 se_access_check: also S-1-5-21-1593769616-160655940-3590153233-1147 se_access_check: ACE 0: type 0, flags = 0x00, SID = S-1-1-0 mask = 101f01ff, current desired = 2 [2003/06/15 23:28:16, 5] lib/util_seaccess.c:se_access_check(325) se_access_check: access (2) granted. [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (500, 2147483404) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(495) NT user token of user S-1-5-21-1593769616-160655940-3590153233-2000 contains 17 SIDs SID[ 0]: S-1-5-21-1593769616-160655940-3590153233-2000 SID[ 1]: S-1-5-21-1593769616-160655940-3590153233-513 SID[ 2]: S-1-1-0 SID[ 3]: S-1-5-2 SID[ 4]: S-1-5-11 SID[ 5]: S-1-5-21-1593769616-160655940-3590153233-1011 SID[ 6]: S-1-5-21-1593769616-160655940-3590153233-1013 SID[ 7]: S-1-5-32-550 SID[ 8]: S-1-5-21-1593769616-160655940-3590153233-1029 SID[ 9]: S-1-5-21-1593769616-160655940-3590153233-1033 SID[ 10]: S-1-5-21-1593769616-160655940-3590153233-1035 SID[ 11]: S-1-5-21-1593769616-160655940-3590153233-1039 SID[ 12]: S-1-5-21-1593769616-160655940-3590153233-1041 SID[ 13]: S-1-5-21-1593769616-160655940-3590153233-1045 SID[ 14]: S-1-5-21-1593769616-160655940-3590153233-1067 SID[ 15]: S-1-5-21-1593769616-160655940-3590153233-512 SID[ 16]: S-1-5-21-1593769616-160655940-3590153233-1147 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 500 Primary group is 2147483404 and contains 13 supplementary groups Group[ 0]: 2147483404 Group[ 1]: 5 Group[ 2]: 6 Group[ 3]: 7 Group[ 4]: 14 Group[ 5]: 16 Group[ 6]: 17 Group[ 7]: 19 Group[ 8]: 20 Group[ 9]: 22 Group[ 10]: 33 Group[ 11]: 71 Group[ 12]: 73 [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_user(203) change_to_user uid=(0,500) gid=(0,2147483404) [2003/06/15 23:28:16, 1] smbd/service.c:make_connection_snum(690) maggot (192.168.1.252) connect to service jht initially as user jht (uid=500, gid=2147483404) (pid 16986) [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(490) NT user token: (NULL) [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_root_user(218) change_to_root_user: now uid=(0,0) gid=(0,0) [2003/06/15 23:28:16, 3] smbd/reply.c:reply_tcon_and_X(308) tconX service=JHT [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=89 smb_com=0x73 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=18433 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=36225 smt_wct=3 smb_vwv[ 0]= 117 (0x75) smb_vwv[ 1]= 72 (0x48) smb_vwv[ 2]= 0 (0x0) smb_bcc=31 [2003/06/15 23:28:16, 10] lib/util.c:dump_data(1887) [000] 55 6E 69 78 00 53 61 6D 62 61 20 33 2E 30 2E 30 Unix.Sam ba 3.0.0 [010] 62 65 74 61 32 00 4D 49 44 45 41 52 54 48 00 beta2.MI DEARTH. [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,93) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,93) wrote 93 [2003/06/15 23:28:16, 10] lib/util_sock.c:read_smb_length_return_keepalive(463) got smb length of 35 [2003/06/15 23:28:16, 6] smbd/process.c:process_smb(881) got message type 0x0 of len 0x23 [2003/06/15 23:28:16, 3] smbd/process.c:process_smb(882) Transaction 3 of length 39 [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=35 smb_com=0x80 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=32768 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=2562 smt_wct=0 smb_bcc=0 [2003/06/15 23:28:16, 3] smbd/process.c:switch_message(676) switch message SMBdskattr (pid 16986) [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (500, 2147483404) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(495) NT user token of user S-1-5-21-1593769616-160655940-3590153233-2000 contains 17 SIDs SID[ 0]: S-1-5-21-1593769616-160655940-3590153233-2000 SID[ 1]: S-1-5-21-1593769616-160655940-3590153233-513 SID[ 2]: S-1-1-0 SID[ 3]: S-1-5-2 SID[ 4]: S-1-5-11 SID[ 5]: S-1-5-21-1593769616-160655940-3590153233-1011 SID[ 6]: S-1-5-21-1593769616-160655940-3590153233-1013 SID[ 7]: S-1-5-32-550 SID[ 8]: S-1-5-21-1593769616-160655940-3590153233-1029 SID[ 9]: S-1-5-21-1593769616-160655940-3590153233-1033 SID[ 10]: S-1-5-21-1593769616-160655940-3590153233-1035 SID[ 11]: S-1-5-21-1593769616-160655940-3590153233-1039 SID[ 12]: S-1-5-21-1593769616-160655940-3590153233-1041 SID[ 13]: S-1-5-21-1593769616-160655940-3590153233-1045 SID[ 14]: S-1-5-21-1593769616-160655940-3590153233-1067 SID[ 15]: S-1-5-21-1593769616-160655940-3590153233-512 SID[ 16]: S-1-5-21-1593769616-160655940-3590153233-1147 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 500 Primary group is 2147483404 and contains 13 supplementary groups Group[ 0]: 2147483404 Group[ 1]: 5 Group[ 2]: 6 Group[ 3]: 7 Group[ 4]: 14 Group[ 5]: 16 Group[ 6]: 17 Group[ 7]: 19 Group[ 8]: 20 Group[ 9]: 22 Group[ 10]: 33 Group[ 11]: 71 Group[ 12]: 73 [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_user(203) change_to_user uid=(0,500) gid=(0,2147483404) [2003/06/15 23:28:16, 4] smbd/vfs.c:vfs_ChDir(611) vfs_ChDir to /home/jht [2003/06/15 23:28:16, 3] smbd/reply.c:reply_dskattr(588) dskattr dfree=36882 [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=45 smb_com=0x80 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=51201 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=2562 smt_wct=5 smb_vwv[ 0]=65387 (0xFF6B) smb_vwv[ 1]= 128 (0x80) smb_vwv[ 2]= 512 (0x200) smb_vwv[ 3]=36882 (0x9012) smb_vwv[ 4]= 0 (0x0) smb_bcc=0 [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,49) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,49) wrote 49 [2003/06/15 23:28:16, 10] lib/util_sock.c:read_smb_length_return_keepalive(463) got smb length of 116 [2003/06/15 23:28:16, 6] smbd/process.c:process_smb(881) got message type 0x0 of len 0x74 [2003/06/15 23:28:16, 3] smbd/process.c:process_smb(882) Transaction 4 of length 120 [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=116 smb_com=0x2d smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=32768 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=36353 smt_wct=15 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 7 (0x7) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 22 (0x16) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 3870 (0xF1E) smb_vwv[ 7]=16109 (0x3EED) smb_vwv[ 8]= 1 (0x1) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 0 (0x0) smb_vwv[14]= 0 (0x0) smb_bcc=51 [2003/06/15 23:28:16, 10] lib/util.c:dump_data(1887) [000] ED 5C 00 53 00 75 00 6E 00 20 00 43 00 6F 00 6E í\.S.u.n . .C.o.n [010] 00 74 00 72 00 61 00 63 00 74 00 20 00 50 00 61 .t.r.a.c .t. .P.a [020] 00 63 00 6B 00 65 00 74 00 2E 00 70 00 64 00 66 .c.k.e.t ...p.d.f [030] 00 00 00 ... [2003/06/15 23:28:16, 3] smbd/process.c:switch_message(676) switch message SMBopenX (pid 16986) [2003/06/15 23:28:16, 4] smbd/uid.c:change_to_user(122) change_to_user: Skipping user change - already user [2003/06/15 23:28:16, 5] smbd/filename.c:unix_convert(114) unix_convert called on file "\Sun Contract Packet.pdf" [2003/06/15 23:28:16, 3] lib/util.c:unix_clean_name(580) unix_clean_name [/Sun Contract Packet.pdf] [2003/06/15 23:28:16, 5] smbd/statcache.c:stat_cache_add(172) stat_cache_add: Added entry SUN CONTRACT PACKET.PDF -> Sun Contract Packet.pdf [2003/06/15 23:28:16, 5] smbd/filename.c:unix_convert(183) conversion finished Sun Contract Packet.pdf -> Sun Contract Packet.pdf [2003/06/15 23:28:16, 3] smbd/dosmode.c:unix_mode(110) unix_mode(Sun Contract Packet.pdf) returning 0744 [2003/06/15 23:28:16, 5] smbd/files.c:file_new(122) allocated file structure 5354, fnum = 9450 (1 used) [2003/06/15 23:28:16, 10] smbd/open.c:open_file_shared1(832) open_file_shared: fname = Sun Contract Packet.pdf, share_mode = 0, ofun = 1, mode = 744, oplock request = 3 [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1562) is_in_path: match not found [2003/06/15 23:28:16, 3] lib/util.c:unix_clean_name(580) unix_clean_name [Sun Contract Packet.pdf] [2003/06/15 23:28:16, 4] smbd/open.c:open_file_shared1(998) calling open_file with flags=0x0 flags2=0x0 mode=0744 [2003/06/15 23:28:16, 10] smbd/open.c:fd_open(51) fd_open: name Sun Contract Packet.pdf, flags = 00 mode = 0744, fd = 28. [2003/06/15 23:28:16, 2] smbd/open.c:open_file(240) JHT opened file Sun Contract Packet.pdf read=Yes write=No (numopen=1) [2003/06/15 23:28:16, 10] smbd/open.c:open_file_shared1(1100) open_file_shared : share_mode = 0 [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1562) is_in_path: match not found [2003/06/15 23:28:16, 3] smbd/oplock_linux.c:linux_set_kernel_oplock(185) linux_set_kernel_oplock: got kernel oplock on file Sun Contract Packet.pdf, dev = 302, inode = 5627, file_id = 1 [2003/06/15 23:28:16, 5] smbd/oplock.c:set_file_oplock(218) set_file_oplock: granted oplock on file Sun Contract Packet.pdf, dev = 302, inode = 5627, file_id = 1, tv_sec = 3eed5570, tv_usec = 4c014 [2003/06/15 23:28:16, 10] locking/locking.c:set_share_mode(650) set_share_mode: creating entry for file Sun Contract Packet.pdf. num_share_modes = 1 [2003/06/15 23:28:16, 10] locking/locking.c:print_share_mode_table(402) print_share_mode_table: share_mode_entry[0]: pid = 16986, share_mode = 0x0, desired_access = 0x1, port = 0x80de, type= 0x3, file_id = 1, dev = 0x302, inode = 5627 [2003/06/15 23:28:16, 8] smbd/dosmode.c:dos_mode(122) dos_mode: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1539) is_in_path: no name list. [2003/06/15 23:28:16, 8] smbd/dosmode.c:dos_mode(168) dos_mode returning [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=65 smb_com=0x2d smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=51201 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=36353 smt_wct=15 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 9450 (0x24EA) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]=11214 (0x2BCE) smb_vwv[ 5]=16106 (0x3EEA) smb_vwv[ 6]=61610 (0xF0AA) smb_vwv[ 7]= 4 (0x4) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]=32769 (0x8001) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 0 (0x0) smb_vwv[14]= 0 (0x0) smb_bcc=0 [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,69) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,69) wrote 69 [2003/06/15 23:28:16, 10] lib/util_sock.c:read_smb_length_return_keepalive(463) got smb length of 116 [2003/06/15 23:28:16, 6] smbd/process.c:process_smb(881) got message type 0x0 of len 0x74 [2003/06/15 23:28:16, 3] smbd/process.c:process_smb(882) Transaction 5 of length 120 [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=116 smb_com=0x2d smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=32768 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=36481 smt_wct=15 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 7 (0x7) smb_vwv[ 3]= 64 (0x40) smb_vwv[ 4]= 22 (0x16) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 3870 (0xF1E) smb_vwv[ 7]=16109 (0x3EED) smb_vwv[ 8]= 1 (0x1) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 0 (0x0) smb_vwv[14]= 0 (0x0) smb_bcc=51 [2003/06/15 23:28:16, 10] lib/util.c:dump_data(1887) [000] ED 5C 00 53 00 75 00 6E 00 20 00 43 00 6F 00 6E í\.S.u.n . .C.o.n [010] 00 74 00 72 00 61 00 63 00 74 00 20 00 50 00 61 .t.r.a.c .t. .P.a [020] 00 63 00 6B 00 65 00 74 00 2E 00 70 00 64 00 66 .c.k.e.t ...p.d.f [030] 00 00 00 ... [2003/06/15 23:28:16, 3] smbd/process.c:switch_message(676) switch message SMBopenX (pid 16986) [2003/06/15 23:28:16, 4] smbd/uid.c:change_to_user(122) change_to_user: Skipping user change - already user [2003/06/15 23:28:16, 5] smbd/filename.c:unix_convert(114) unix_convert called on file "\Sun Contract Packet.pdf" [2003/06/15 23:28:16, 3] lib/util.c:unix_clean_name(580) unix_clean_name [/Sun Contract Packet.pdf] [2003/06/15 23:28:16, 3] smbd/dosmode.c:unix_mode(110) unix_mode(Sun Contract Packet.pdf) returning 0744 [2003/06/15 23:28:16, 5] smbd/files.c:file_new(122) allocated file structure 5355, fnum = 9451 (2 used) [2003/06/15 23:28:16, 10] smbd/open.c:open_file_shared1(832) open_file_shared: fname = Sun Contract Packet.pdf, share_mode = 40, ofun = 1, mode = 744, oplock request = 3 [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1562) is_in_path: match not found [2003/06/15 23:28:16, 3] lib/util.c:unix_clean_name(580) unix_clean_name [Sun Contract Packet.pdf] [2003/06/15 23:28:16, 10] locking/locking.c:get_share_modes(446) get_share_modes: share_mode_entry[0]: pid = 16986, share_mode = 0x0, desired_access = 0x1, port = 0x80de, type= 0x3, file_id = 1, dev = 0x302, inode = 5627 [2003/06/15 23:28:16, 5] smbd/open.c:open_mode_check(618) open_mode_check: oplock_request = 3, breaking oplock (3) on file Sun Contract Packet.pdf, dev = 302, inode = 5627 [2003/06/15 23:28:16, 5] smbd/oplock.c:request_oplock_break(925) request_oplock_break: breaking our own oplock [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(526) initial_break_processing: called for dev = 302, inode = 5627 file_id = 1 Current oplocks_open (exclusive = 1, levelII = 0) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,55) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,55) wrote 55 [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(490) NT user token: (NULL) [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_root_user(218) change_to_root_user: now uid=(0,0) gid=(0,0) [2003/06/15 23:28:16, 5] smbd/vfs.c:vfs_GetWd(729) vfs_GetWd /home/jht, inode 3, dev 770 [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(526) initial_break_processing: called for dev = 302, inode = 5627 file_id = 1 Current oplocks_open (exclusive = 1, levelII = 0) [2003/06/15 23:28:16, 10] lib/util_sock.c:read_smb_length_return_keepalive(463) got smb length of 41 [2003/06/15 23:28:16, 6] smbd/process.c:process_smb(881) got message type 0x0 of len 0x29 [2003/06/15 23:28:16, 3] smbd/process.c:process_smb(882) Transaction 5 of length 45 [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=41 smb_com=0x4 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=32768 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=2690 smt_wct=3 smb_vwv[ 0]= 9450 (0x24EA) smb_vwv[ 1]=65535 (0xFFFF) smb_vwv[ 2]=65535 (0xFFFF) smb_bcc=0 [2003/06/15 23:28:16, 3] smbd/process.c:switch_message(676) switch message SMBclose (pid 16986) [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (500, 2147483404) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(495) NT user token of user S-1-5-21-1593769616-160655940-3590153233-2000 contains 17 SIDs SID[ 0]: S-1-5-21-1593769616-160655940-3590153233-2000 SID[ 1]: S-1-5-21-1593769616-160655940-3590153233-513 SID[ 2]: S-1-1-0 SID[ 3]: S-1-5-2 SID[ 4]: S-1-5-11 SID[ 5]: S-1-5-21-1593769616-160655940-3590153233-1011 SID[ 6]: S-1-5-21-1593769616-160655940-3590153233-1013 SID[ 7]: S-1-5-32-550 SID[ 8]: S-1-5-21-1593769616-160655940-3590153233-1029 SID[ 9]: S-1-5-21-1593769616-160655940-3590153233-1033 SID[ 10]: S-1-5-21-1593769616-160655940-3590153233-1035 SID[ 11]: S-1-5-21-1593769616-160655940-3590153233-1039 SID[ 12]: S-1-5-21-1593769616-160655940-3590153233-1041 SID[ 13]: S-1-5-21-1593769616-160655940-3590153233-1045 SID[ 14]: S-1-5-21-1593769616-160655940-3590153233-1067 SID[ 15]: S-1-5-21-1593769616-160655940-3590153233-512 SID[ 16]: S-1-5-21-1593769616-160655940-3590153233-1147 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 500 Primary group is 2147483404 and contains 13 supplementary groups Group[ 0]: 2147483404 Group[ 1]: 5 Group[ 2]: 6 Group[ 3]: 7 Group[ 4]: 14 Group[ 5]: 16 Group[ 6]: 17 Group[ 7]: 19 Group[ 8]: 20 Group[ 9]: 22 Group[ 10]: 33 Group[ 11]: 71 Group[ 12]: 73 [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_user(203) change_to_user uid=(0,500) gid=(0,2147483404) [2003/06/15 23:28:16, 3] smbd/reply.c:reply_close(2409) close fd=28 fnum=9450 (numopen=1) [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(561) del_share_entry: num_share_modes = 1 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(566) del_share_entry: deleted share_mode_entry[0]: pid = 16986, share_mode = 0x0, desired_access = 0x1, port = 0x80de, type= 0x3, file_id = 1, dev = 0x302, inode = 5627 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(574) del_share_entry: deleting entry 0 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(596) del_share_entry: Remaining table. [2003/06/15 23:28:16, 10] smbd/close.c:close_normal_file(167) close_normal_file: share_entry_count = 0 for file Sun Contract Packet.pdf [2003/06/15 23:28:16, 10] smbd/oplock_linux.c:linux_release_kernel_oplock(196) linux_release_kernel_oplock: file Sun Contract Packet.pdf, dev = 302, inode = 5627 file_id = 1 has kernel oplock state of 1. [2003/06/15 23:28:16, 10] locking/posix.c:posix_locking_close_file(1241) posix_locking_close_file: file Sun Contract Packet.pdf has no outstanding locks. [2003/06/15 23:28:16, 2] smbd/close.c:close_normal_file(228) jht closed file Sun Contract Packet.pdf (numopen=0) [2003/06/15 23:28:16, 5] smbd/files.c:file_free(357) freed files structure 9450 (1 used) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) 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=6411 smb_uid=100 smb_mid=2690 smt_wct=0 smb_bcc=0 [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,39) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,39) wrote 39 [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(526) initial_break_processing: called for dev = 302, inode = 5627 file_id = 1 Current oplocks_open (exclusive = 0, levelII = 0) [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(543) initial_break_processing: cannot find open file with dev = 302, inode = 5627 file_id = 1allowing break to succeed. [2003/06/15 23:28:16, 4] smbd/uid.c:change_to_user(122) change_to_user: Skipping user change - already user [2003/06/15 23:28:16, 3] smbd/oplock.c:oplock_break(891) oplock_break: returning success for dev = 302, inode = 5627, file_id = 1 Current exclusive_oplocks_open = 0 [2003/06/15 23:28:16, 4] smbd/open.c:open_file_shared1(998) calling open_file with flags=0x0 flags2=0x0 mode=0744 [2003/06/15 23:28:16, 10] smbd/open.c:fd_open(51) fd_open: name Sun Contract Packet.pdf, flags = 00 mode = 0744, fd = 28. [2003/06/15 23:28:16, 2] smbd/open.c:open_file(240) JHT opened file Sun Contract Packet.pdf read=Yes write=No (numopen=1) [2003/06/15 23:28:16, 10] smbd/open.c:open_file_shared1(1100) open_file_shared : share_mode = 40 [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1562) is_in_path: match not found [2003/06/15 23:28:16, 3] smbd/oplock_linux.c:linux_set_kernel_oplock(185) linux_set_kernel_oplock: got kernel oplock on file Sun Contract Packet.pdf, dev = 302, inode = 5627, file_id = 2 [2003/06/15 23:28:16, 5] smbd/oplock.c:set_file_oplock(218) set_file_oplock: granted oplock on file Sun Contract Packet.pdf, dev = 302, inode = 5627, file_id = 2, tv_sec = 3eed5570, tv_usec = 4c654 [2003/06/15 23:28:16, 10] locking/locking.c:set_share_mode(650) set_share_mode: creating entry for file Sun Contract Packet.pdf. num_share_modes = 1 [2003/06/15 23:28:16, 10] locking/locking.c:print_share_mode_table(402) print_share_mode_table: share_mode_entry[0]: pid = 16986, share_mode = 0x40, desired_access = 0x1, port = 0x80de, type= 0x3, file_id = 2, dev = 0x302, inode = 5627 [2003/06/15 23:28:16, 8] smbd/dosmode.c:dos_mode(122) dos_mode: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1539) is_in_path: no name list. [2003/06/15 23:28:16, 8] smbd/dosmode.c:dos_mode(168) dos_mode returning [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=65 smb_com=0x2d smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=51201 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=36481 smt_wct=15 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 9451 (0x24EB) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]=11214 (0x2BCE) smb_vwv[ 5]=16106 (0x3EEA) smb_vwv[ 6]=61610 (0xF0AA) smb_vwv[ 7]= 4 (0x4) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]=32769 (0x8001) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 0 (0x0) smb_vwv[14]= 0 (0x0) smb_bcc=0 [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,69) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,69) wrote 69 [2003/06/15 23:28:16, 10] lib/util_sock.c:read_smb_length_return_keepalive(463) got smb length of 116 [2003/06/15 23:28:16, 6] smbd/process.c:process_smb(881) got message type 0x0 of len 0x74 [2003/06/15 23:28:16, 3] smbd/process.c:process_smb(882) Transaction 7 of length 120 [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=116 smb_com=0x2d smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=32768 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=36609 smt_wct=15 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 7 (0x7) smb_vwv[ 3]= 160 (0xA0) smb_vwv[ 4]= 22 (0x16) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 3870 (0xF1E) smb_vwv[ 7]=16109 (0x3EED) smb_vwv[ 8]= 1 (0x1) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 0 (0x0) smb_vwv[14]= 0 (0x0) smb_bcc=51 [2003/06/15 23:28:16, 10] lib/util.c:dump_data(1887) [000] ED 5C 00 53 00 75 00 6E 00 20 00 43 00 6F 00 6E í\.S.u.n . .C.o.n [010] 00 74 00 72 00 61 00 63 00 74 00 20 00 50 00 61 .t.r.a.c .t. .P.a [020] 00 63 00 6B 00 65 00 74 00 2E 00 70 00 64 00 66 .c.k.e.t ...p.d.f [030] 00 00 00 ... [2003/06/15 23:28:16, 3] smbd/process.c:switch_message(676) switch message SMBopenX (pid 16986) [2003/06/15 23:28:16, 4] smbd/uid.c:change_to_user(122) change_to_user: Skipping user change - already user [2003/06/15 23:28:16, 5] smbd/filename.c:unix_convert(114) unix_convert called on file "\Sun Contract Packet.pdf" [2003/06/15 23:28:16, 3] lib/util.c:unix_clean_name(580) unix_clean_name [/Sun Contract Packet.pdf] [2003/06/15 23:28:16, 3] smbd/dosmode.c:unix_mode(110) unix_mode(Sun Contract Packet.pdf) returning 0744 [2003/06/15 23:28:16, 5] smbd/files.c:file_new(122) allocated file structure 5356, fnum = 9452 (2 used) [2003/06/15 23:28:16, 10] smbd/open.c:open_file_shared1(832) open_file_shared: fname = Sun Contract Packet.pdf, share_mode = a0, ofun = 1, mode = 744, oplock request = 3 [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1562) is_in_path: match not found [2003/06/15 23:28:16, 3] lib/util.c:unix_clean_name(580) unix_clean_name [Sun Contract Packet.pdf] [2003/06/15 23:28:16, 10] locking/locking.c:get_share_modes(446) get_share_modes: share_mode_entry[0]: pid = 16986, share_mode = 0x40, desired_access = 0x1, port = 0x80de, type= 0x3, file_id = 2, dev = 0x302, inode = 5627 [2003/06/15 23:28:16, 5] smbd/open.c:open_mode_check(618) open_mode_check: oplock_request = 3, breaking oplock (3) on file Sun Contract Packet.pdf, dev = 302, inode = 5627 [2003/06/15 23:28:16, 5] smbd/oplock.c:request_oplock_break(925) request_oplock_break: breaking our own oplock [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(526) initial_break_processing: called for dev = 302, inode = 5627 file_id = 2 Current oplocks_open (exclusive = 1, levelII = 0) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,55) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,55) wrote 55 [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(490) NT user token: (NULL) [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_root_user(218) change_to_root_user: now uid=(0,0) gid=(0,0) [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(526) initial_break_processing: called for dev = 302, inode = 5627 file_id = 2 Current oplocks_open (exclusive = 1, levelII = 0) [2003/06/15 23:28:16, 10] lib/util_sock.c:read_smb_length_return_keepalive(463) got smb length of 41 [2003/06/15 23:28:16, 6] smbd/process.c:process_smb(881) got message type 0x0 of len 0x29 [2003/06/15 23:28:16, 3] smbd/process.c:process_smb(882) Transaction 7 of length 45 [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=41 smb_com=0x4 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=32768 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=2818 smt_wct=3 smb_vwv[ 0]= 9451 (0x24EB) smb_vwv[ 1]=65535 (0xFFFF) smb_vwv[ 2]=65535 (0xFFFF) smb_bcc=0 [2003/06/15 23:28:16, 3] smbd/process.c:switch_message(676) switch message SMBclose (pid 16986) [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (500, 2147483404) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(495) NT user token of user S-1-5-21-1593769616-160655940-3590153233-2000 contains 17 SIDs SID[ 0]: S-1-5-21-1593769616-160655940-3590153233-2000 SID[ 1]: S-1-5-21-1593769616-160655940-3590153233-513 SID[ 2]: S-1-1-0 SID[ 3]: S-1-5-2 SID[ 4]: S-1-5-11 SID[ 5]: S-1-5-21-1593769616-160655940-3590153233-1011 SID[ 6]: S-1-5-21-1593769616-160655940-3590153233-1013 SID[ 7]: S-1-5-32-550 SID[ 8]: S-1-5-21-1593769616-160655940-3590153233-1029 SID[ 9]: S-1-5-21-1593769616-160655940-3590153233-1033 SID[ 10]: S-1-5-21-1593769616-160655940-3590153233-1035 SID[ 11]: S-1-5-21-1593769616-160655940-3590153233-1039 SID[ 12]: S-1-5-21-1593769616-160655940-3590153233-1041 SID[ 13]: S-1-5-21-1593769616-160655940-3590153233-1045 SID[ 14]: S-1-5-21-1593769616-160655940-3590153233-1067 SID[ 15]: S-1-5-21-1593769616-160655940-3590153233-512 SID[ 16]: S-1-5-21-1593769616-160655940-3590153233-1147 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 500 Primary group is 2147483404 and contains 13 supplementary groups Group[ 0]: 2147483404 Group[ 1]: 5 Group[ 2]: 6 Group[ 3]: 7 Group[ 4]: 14 Group[ 5]: 16 Group[ 6]: 17 Group[ 7]: 19 Group[ 8]: 20 Group[ 9]: 22 Group[ 10]: 33 Group[ 11]: 71 Group[ 12]: 73 [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_user(203) change_to_user uid=(0,500) gid=(0,2147483404) [2003/06/15 23:28:16, 3] smbd/reply.c:reply_close(2409) close fd=28 fnum=9451 (numopen=1) [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(561) del_share_entry: num_share_modes = 1 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(566) del_share_entry: deleted share_mode_entry[0]: pid = 16986, share_mode = 0x40, desired_access = 0x1, port = 0x80de, type= 0x3, file_id = 2, dev = 0x302, inode = 5627 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(574) del_share_entry: deleting entry 0 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(596) del_share_entry: Remaining table. [2003/06/15 23:28:16, 10] smbd/close.c:close_normal_file(167) close_normal_file: share_entry_count = 0 for file Sun Contract Packet.pdf [2003/06/15 23:28:16, 10] smbd/oplock_linux.c:linux_release_kernel_oplock(196) linux_release_kernel_oplock: file Sun Contract Packet.pdf, dev = 302, inode = 5627 file_id = 2 has kernel oplock state of 1. [2003/06/15 23:28:16, 10] locking/posix.c:posix_locking_close_file(1241) posix_locking_close_file: file Sun Contract Packet.pdf has no outstanding locks. [2003/06/15 23:28:16, 2] smbd/close.c:close_normal_file(228) jht closed file Sun Contract Packet.pdf (numopen=0) [2003/06/15 23:28:16, 5] smbd/files.c:file_free(357) freed files structure 9451 (1 used) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) 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=6411 smb_uid=100 smb_mid=2818 smt_wct=0 smb_bcc=0 [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,39) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,39) wrote 39 [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(526) initial_break_processing: called for dev = 302, inode = 5627 file_id = 2 Current oplocks_open (exclusive = 0, levelII = 0) [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(543) initial_break_processing: cannot find open file with dev = 302, inode = 5627 file_id = 2allowing break to succeed. [2003/06/15 23:28:16, 4] smbd/uid.c:change_to_user(122) change_to_user: Skipping user change - already user [2003/06/15 23:28:16, 3] smbd/oplock.c:oplock_break(891) oplock_break: returning success for dev = 302, inode = 5627, file_id = 2 Current exclusive_oplocks_open = 0 [2003/06/15 23:28:16, 4] smbd/open.c:open_file_shared1(998) calling open_file with flags=0x0 flags2=0x0 mode=0744 [2003/06/15 23:28:16, 10] smbd/open.c:fd_open(51) fd_open: name Sun Contract Packet.pdf, flags = 00 mode = 0744, fd = 28. [2003/06/15 23:28:16, 2] smbd/open.c:open_file(240) JHT opened file Sun Contract Packet.pdf read=Yes write=No (numopen=1) [2003/06/15 23:28:16, 10] smbd/open.c:open_file_shared1(1100) open_file_shared : share_mode = 20 [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1562) is_in_path: match not found [2003/06/15 23:28:16, 3] smbd/oplock_linux.c:linux_set_kernel_oplock(185) linux_set_kernel_oplock: got kernel oplock on file Sun Contract Packet.pdf, dev = 302, inode = 5627, file_id = 3 [2003/06/15 23:28:16, 5] smbd/oplock.c:set_file_oplock(218) set_file_oplock: granted oplock on file Sun Contract Packet.pdf, dev = 302, inode = 5627, file_id = 3, tv_sec = 3eed5570, tv_usec = 4d2e9 [2003/06/15 23:28:16, 10] locking/locking.c:set_share_mode(650) set_share_mode: creating entry for file Sun Contract Packet.pdf. num_share_modes = 1 [2003/06/15 23:28:16, 10] locking/locking.c:print_share_mode_table(402) print_share_mode_table: share_mode_entry[0]: pid = 16986, share_mode = 0x20, desired_access = 0x1, port = 0x80de, type= 0x3, file_id = 3, dev = 0x302, inode = 5627 [2003/06/15 23:28:16, 8] smbd/dosmode.c:dos_mode(122) dos_mode: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1539) is_in_path: no name list. [2003/06/15 23:28:16, 8] smbd/dosmode.c:dos_mode(168) dos_mode returning [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=65 smb_com=0x2d smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=51201 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=36609 smt_wct=15 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 9452 (0x24EC) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]=11214 (0x2BCE) smb_vwv[ 5]=16106 (0x3EEA) smb_vwv[ 6]=61610 (0xF0AA) smb_vwv[ 7]= 4 (0x4) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]=32769 (0x8001) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 0 (0x0) smb_vwv[14]= 0 (0x0) smb_bcc=0 [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,69) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,69) wrote 69 [2003/06/15 23:28:16, 10] lib/util_sock.c:read_smb_length_return_keepalive(463) got smb length of 116 [2003/06/15 23:28:16, 6] smbd/process.c:process_smb(881) got message type 0x0 of len 0x74 [2003/06/15 23:28:16, 3] smbd/process.c:process_smb(882) Transaction 9 of length 120 [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=116 smb_com=0x2d smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=32768 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=36737 smt_wct=15 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 7 (0x7) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 22 (0x16) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 3870 (0xF1E) smb_vwv[ 7]=16109 (0x3EED) smb_vwv[ 8]= 1 (0x1) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 0 (0x0) smb_vwv[14]= 0 (0x0) smb_bcc=51 [2003/06/15 23:28:16, 10] lib/util.c:dump_data(1887) [000] ED 5C 00 53 00 75 00 6E 00 20 00 43 00 6F 00 6E í\.S.u.n . .C.o.n [010] 00 74 00 72 00 61 00 63 00 74 00 20 00 50 00 61 .t.r.a.c .t. .P.a [020] 00 63 00 6B 00 65 00 74 00 2E 00 70 00 64 00 66 .c.k.e.t ...p.d.f [030] 00 00 00 ... [2003/06/15 23:28:16, 3] smbd/process.c:switch_message(676) switch message SMBopenX (pid 16986) [2003/06/15 23:28:16, 4] smbd/uid.c:change_to_user(122) change_to_user: Skipping user change - already user [2003/06/15 23:28:16, 5] smbd/filename.c:unix_convert(114) unix_convert called on file "\Sun Contract Packet.pdf" [2003/06/15 23:28:16, 3] lib/util.c:unix_clean_name(580) unix_clean_name [/Sun Contract Packet.pdf] [2003/06/15 23:28:16, 3] smbd/dosmode.c:unix_mode(110) unix_mode(Sun Contract Packet.pdf) returning 0744 [2003/06/15 23:28:16, 5] smbd/files.c:file_new(122) allocated file structure 5357, fnum = 9453 (2 used) [2003/06/15 23:28:16, 10] smbd/open.c:open_file_shared1(832) open_file_shared: fname = Sun Contract Packet.pdf, share_mode = 0, ofun = 1, mode = 744, oplock request = 3 [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1562) is_in_path: match not found [2003/06/15 23:28:16, 3] lib/util.c:unix_clean_name(580) unix_clean_name [Sun Contract Packet.pdf] [2003/06/15 23:28:16, 10] locking/locking.c:get_share_modes(446) get_share_modes: share_mode_entry[0]: pid = 16986, share_mode = 0x20, desired_access = 0x1, port = 0x80de, type= 0x3, file_id = 3, dev = 0x302, inode = 5627 [2003/06/15 23:28:16, 5] smbd/open.c:open_mode_check(618) open_mode_check: oplock_request = 3, breaking oplock (3) on file Sun Contract Packet.pdf, dev = 302, inode = 5627 [2003/06/15 23:28:16, 5] smbd/oplock.c:request_oplock_break(925) request_oplock_break: breaking our own oplock [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(526) initial_break_processing: called for dev = 302, inode = 5627 file_id = 3 Current oplocks_open (exclusive = 1, levelII = 0) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,55) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,55) wrote 55 [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(490) NT user token: (NULL) [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_root_user(218) change_to_root_user: now uid=(0,0) gid=(0,0) [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(526) initial_break_processing: called for dev = 302, inode = 5627 file_id = 3 Current oplocks_open (exclusive = 1, levelII = 0) [2003/06/15 23:28:16, 10] lib/util_sock.c:read_smb_length_return_keepalive(463) got smb length of 41 [2003/06/15 23:28:16, 6] smbd/process.c:process_smb(881) got message type 0x0 of len 0x29 [2003/06/15 23:28:16, 3] smbd/process.c:process_smb(882) Transaction 9 of length 45 [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=41 smb_com=0x4 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=32768 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=2946 smt_wct=3 smb_vwv[ 0]= 9452 (0x24EC) smb_vwv[ 1]=65535 (0xFFFF) smb_vwv[ 2]=65535 (0xFFFF) smb_bcc=0 [2003/06/15 23:28:16, 3] smbd/process.c:switch_message(676) switch message SMBclose (pid 16986) [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (500, 2147483404) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(495) NT user token of user S-1-5-21-1593769616-160655940-3590153233-2000 contains 17 SIDs SID[ 0]: S-1-5-21-1593769616-160655940-3590153233-2000 SID[ 1]: S-1-5-21-1593769616-160655940-3590153233-513 SID[ 2]: S-1-1-0 SID[ 3]: S-1-5-2 SID[ 4]: S-1-5-11 SID[ 5]: S-1-5-21-1593769616-160655940-3590153233-1011 SID[ 6]: S-1-5-21-1593769616-160655940-3590153233-1013 SID[ 7]: S-1-5-32-550 SID[ 8]: S-1-5-21-1593769616-160655940-3590153233-1029 SID[ 9]: S-1-5-21-1593769616-160655940-3590153233-1033 SID[ 10]: S-1-5-21-1593769616-160655940-3590153233-1035 SID[ 11]: S-1-5-21-1593769616-160655940-3590153233-1039 SID[ 12]: S-1-5-21-1593769616-160655940-3590153233-1041 SID[ 13]: S-1-5-21-1593769616-160655940-3590153233-1045 SID[ 14]: S-1-5-21-1593769616-160655940-3590153233-1067 SID[ 15]: S-1-5-21-1593769616-160655940-3590153233-512 SID[ 16]: S-1-5-21-1593769616-160655940-3590153233-1147 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 500 Primary group is 2147483404 and contains 13 supplementary groups Group[ 0]: 2147483404 Group[ 1]: 5 Group[ 2]: 6 Group[ 3]: 7 Group[ 4]: 14 Group[ 5]: 16 Group[ 6]: 17 Group[ 7]: 19 Group[ 8]: 20 Group[ 9]: 22 Group[ 10]: 33 Group[ 11]: 71 Group[ 12]: 73 [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_user(203) change_to_user uid=(0,500) gid=(0,2147483404) [2003/06/15 23:28:16, 3] smbd/reply.c:reply_close(2409) close fd=28 fnum=9452 (numopen=1) [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(561) del_share_entry: num_share_modes = 1 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(566) del_share_entry: deleted share_mode_entry[0]: pid = 16986, share_mode = 0x20, desired_access = 0x1, port = 0x80de, type= 0x3, file_id = 3, dev = 0x302, inode = 5627 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(574) del_share_entry: deleting entry 0 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(596) del_share_entry: Remaining table. [2003/06/15 23:28:16, 10] smbd/close.c:close_normal_file(167) close_normal_file: share_entry_count = 0 for file Sun Contract Packet.pdf [2003/06/15 23:28:16, 10] smbd/oplock_linux.c:linux_release_kernel_oplock(196) linux_release_kernel_oplock: file Sun Contract Packet.pdf, dev = 302, inode = 5627 file_id = 3 has kernel oplock state of 1. [2003/06/15 23:28:16, 10] locking/posix.c:posix_locking_close_file(1241) posix_locking_close_file: file Sun Contract Packet.pdf has no outstanding locks. [2003/06/15 23:28:16, 2] smbd/close.c:close_normal_file(228) jht closed file Sun Contract Packet.pdf (numopen=0) [2003/06/15 23:28:16, 5] smbd/files.c:file_free(357) freed files structure 9452 (1 used) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) 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=6411 smb_uid=100 smb_mid=2946 smt_wct=0 smb_bcc=0 [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,39) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,39) wrote 39 [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(526) initial_break_processing: called for dev = 302, inode = 5627 file_id = 3 Current oplocks_open (exclusive = 0, levelII = 0) [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(543) initial_break_processing: cannot find open file with dev = 302, inode = 5627 file_id = 3allowing break to succeed. [2003/06/15 23:28:16, 4] smbd/uid.c:change_to_user(122) change_to_user: Skipping user change - already user [2003/06/15 23:28:16, 3] smbd/oplock.c:oplock_break(891) oplock_break: returning success for dev = 302, inode = 5627, file_id = 3 Current exclusive_oplocks_open = 0 [2003/06/15 23:28:16, 4] smbd/open.c:open_file_shared1(998) calling open_file with flags=0x0 flags2=0x0 mode=0744 [2003/06/15 23:28:16, 10] smbd/open.c:fd_open(51) fd_open: name Sun Contract Packet.pdf, flags = 00 mode = 0744, fd = 28. [2003/06/15 23:28:16, 2] smbd/open.c:open_file(240) JHT opened file Sun Contract Packet.pdf read=Yes write=No (numopen=1) [2003/06/15 23:28:16, 10] smbd/open.c:open_file_shared1(1100) open_file_shared : share_mode = 0 [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1562) is_in_path: match not found [2003/06/15 23:28:16, 3] smbd/oplock_linux.c:linux_set_kernel_oplock(185) linux_set_kernel_oplock: got kernel oplock on file Sun Contract Packet.pdf, dev = 302, inode = 5627, file_id = 4 [2003/06/15 23:28:16, 5] smbd/oplock.c:set_file_oplock(218) set_file_oplock: granted oplock on file Sun Contract Packet.pdf, dev = 302, inode = 5627, file_id = 4, tv_sec = 3eed5570, tv_usec = 4dfd3 [2003/06/15 23:28:16, 10] locking/locking.c:set_share_mode(650) set_share_mode: creating entry for file Sun Contract Packet.pdf. num_share_modes = 1 [2003/06/15 23:28:16, 10] locking/locking.c:print_share_mode_table(402) print_share_mode_table: share_mode_entry[0]: pid = 16986, share_mode = 0x0, desired_access = 0x1, port = 0x80de, type= 0x3, file_id = 4, dev = 0x302, inode = 5627 [2003/06/15 23:28:16, 8] smbd/dosmode.c:dos_mode(122) dos_mode: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1539) is_in_path: no name list. [2003/06/15 23:28:16, 8] smbd/dosmode.c:dos_mode(168) dos_mode returning [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=65 smb_com=0x2d smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=51201 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=36737 smt_wct=15 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 9453 (0x24ED) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]=11214 (0x2BCE) smb_vwv[ 5]=16106 (0x3EEA) smb_vwv[ 6]=61610 (0xF0AA) smb_vwv[ 7]= 4 (0x4) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]=32769 (0x8001) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 0 (0x0) smb_vwv[14]= 0 (0x0) smb_bcc=0 [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,69) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,69) wrote 69 [2003/06/15 23:28:16, 10] lib/util_sock.c:read_smb_length_return_keepalive(463) got smb length of 116 [2003/06/15 23:28:16, 6] smbd/process.c:process_smb(881) got message type 0x0 of len 0x74 [2003/06/15 23:28:16, 3] smbd/process.c:process_smb(882) Transaction 11 of length 120 [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=116 smb_com=0x2d smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=32768 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=36865 smt_wct=15 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 7 (0x7) smb_vwv[ 3]= 64 (0x40) smb_vwv[ 4]= 22 (0x16) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 3870 (0xF1E) smb_vwv[ 7]=16109 (0x3EED) smb_vwv[ 8]= 1 (0x1) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 0 (0x0) smb_vwv[14]= 0 (0x0) smb_bcc=51 [2003/06/15 23:28:16, 10] lib/util.c:dump_data(1887) [000] ED 5C 00 53 00 75 00 6E 00 20 00 43 00 6F 00 6E í\.S.u.n . .C.o.n [010] 00 74 00 72 00 61 00 63 00 74 00 20 00 50 00 61 .t.r.a.c .t. .P.a [020] 00 63 00 6B 00 65 00 74 00 2E 00 70 00 64 00 66 .c.k.e.t ...p.d.f [030] 00 00 00 ... [2003/06/15 23:28:16, 3] smbd/process.c:switch_message(676) switch message SMBopenX (pid 16986) [2003/06/15 23:28:16, 4] smbd/uid.c:change_to_user(122) change_to_user: Skipping user change - already user [2003/06/15 23:28:16, 5] smbd/filename.c:unix_convert(114) unix_convert called on file "\Sun Contract Packet.pdf" [2003/06/15 23:28:16, 3] lib/util.c:unix_clean_name(580) unix_clean_name [/Sun Contract Packet.pdf] [2003/06/15 23:28:16, 3] smbd/dosmode.c:unix_mode(110) unix_mode(Sun Contract Packet.pdf) returning 0744 [2003/06/15 23:28:16, 5] smbd/files.c:file_new(122) allocated file structure 5358, fnum = 9454 (2 used) [2003/06/15 23:28:16, 10] smbd/open.c:open_file_shared1(832) open_file_shared: fname = Sun Contract Packet.pdf, share_mode = 40, ofun = 1, mode = 744, oplock request = 3 [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1562) is_in_path: match not found [2003/06/15 23:28:16, 3] lib/util.c:unix_clean_name(580) unix_clean_name [Sun Contract Packet.pdf] [2003/06/15 23:28:16, 10] locking/locking.c:get_share_modes(446) get_share_modes: share_mode_entry[0]: pid = 16986, share_mode = 0x0, desired_access = 0x1, port = 0x80de, type= 0x3, file_id = 4, dev = 0x302, inode = 5627 [2003/06/15 23:28:16, 5] smbd/open.c:open_mode_check(618) open_mode_check: oplock_request = 3, breaking oplock (3) on file Sun Contract Packet.pdf, dev = 302, inode = 5627 [2003/06/15 23:28:16, 5] smbd/oplock.c:request_oplock_break(925) request_oplock_break: breaking our own oplock [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(526) initial_break_processing: called for dev = 302, inode = 5627 file_id = 4 Current oplocks_open (exclusive = 1, levelII = 0) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,55) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,55) wrote 55 [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(490) NT user token: (NULL) [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_root_user(218) change_to_root_user: now uid=(0,0) gid=(0,0) [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(526) initial_break_processing: called for dev = 302, inode = 5627 file_id = 4 Current oplocks_open (exclusive = 1, levelII = 0) [2003/06/15 23:28:16, 10] lib/util_sock.c:read_smb_length_return_keepalive(463) got smb length of 41 [2003/06/15 23:28:16, 6] smbd/process.c:process_smb(881) got message type 0x0 of len 0x29 [2003/06/15 23:28:16, 3] smbd/process.c:process_smb(882) Transaction 11 of length 45 [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=41 smb_com=0x4 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=32768 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=3074 smt_wct=3 smb_vwv[ 0]= 9453 (0x24ED) smb_vwv[ 1]=65535 (0xFFFF) smb_vwv[ 2]=65535 (0xFFFF) smb_bcc=0 [2003/06/15 23:28:16, 3] smbd/process.c:switch_message(676) switch message SMBclose (pid 16986) [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (500, 2147483404) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(495) NT user token of user S-1-5-21-1593769616-160655940-3590153233-2000 contains 17 SIDs SID[ 0]: S-1-5-21-1593769616-160655940-3590153233-2000 SID[ 1]: S-1-5-21-1593769616-160655940-3590153233-513 SID[ 2]: S-1-1-0 SID[ 3]: S-1-5-2 SID[ 4]: S-1-5-11 SID[ 5]: S-1-5-21-1593769616-160655940-3590153233-1011 SID[ 6]: S-1-5-21-1593769616-160655940-3590153233-1013 SID[ 7]: S-1-5-32-550 SID[ 8]: S-1-5-21-1593769616-160655940-3590153233-1029 SID[ 9]: S-1-5-21-1593769616-160655940-3590153233-1033 SID[ 10]: S-1-5-21-1593769616-160655940-3590153233-1035 SID[ 11]: S-1-5-21-1593769616-160655940-3590153233-1039 SID[ 12]: S-1-5-21-1593769616-160655940-3590153233-1041 SID[ 13]: S-1-5-21-1593769616-160655940-3590153233-1045 SID[ 14]: S-1-5-21-1593769616-160655940-3590153233-1067 SID[ 15]: S-1-5-21-1593769616-160655940-3590153233-512 SID[ 16]: S-1-5-21-1593769616-160655940-3590153233-1147 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 500 Primary group is 2147483404 and contains 13 supplementary groups Group[ 0]: 2147483404 Group[ 1]: 5 Group[ 2]: 6 Group[ 3]: 7 Group[ 4]: 14 Group[ 5]: 16 Group[ 6]: 17 Group[ 7]: 19 Group[ 8]: 20 Group[ 9]: 22 Group[ 10]: 33 Group[ 11]: 71 Group[ 12]: 73 [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_user(203) change_to_user uid=(0,500) gid=(0,2147483404) [2003/06/15 23:28:16, 3] smbd/reply.c:reply_close(2409) close fd=28 fnum=9453 (numopen=1) [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(561) del_share_entry: num_share_modes = 1 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(566) del_share_entry: deleted share_mode_entry[0]: pid = 16986, share_mode = 0x0, desired_access = 0x1, port = 0x80de, type= 0x3, file_id = 4, dev = 0x302, inode = 5627 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(574) del_share_entry: deleting entry 0 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(596) del_share_entry: Remaining table. [2003/06/15 23:28:16, 10] smbd/close.c:close_normal_file(167) close_normal_file: share_entry_count = 0 for file Sun Contract Packet.pdf [2003/06/15 23:28:16, 10] smbd/oplock_linux.c:linux_release_kernel_oplock(196) linux_release_kernel_oplock: file Sun Contract Packet.pdf, dev = 302, inode = 5627 file_id = 4 has kernel oplock state of 1. [2003/06/15 23:28:16, 10] locking/posix.c:posix_locking_close_file(1241) posix_locking_close_file: file Sun Contract Packet.pdf has no outstanding locks. [2003/06/15 23:28:16, 2] smbd/close.c:close_normal_file(228) jht closed file Sun Contract Packet.pdf (numopen=0) [2003/06/15 23:28:16, 5] smbd/files.c:file_free(357) freed files structure 9453 (1 used) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) 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=6411 smb_uid=100 smb_mid=3074 smt_wct=0 smb_bcc=0 [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,39) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,39) wrote 39 [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(526) initial_break_processing: called for dev = 302, inode = 5627 file_id = 4 Current oplocks_open (exclusive = 0, levelII = 0) [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(543) initial_break_processing: cannot find open file with dev = 302, inode = 5627 file_id = 4allowing break to succeed. [2003/06/15 23:28:16, 4] smbd/uid.c:change_to_user(122) change_to_user: Skipping user change - already user [2003/06/15 23:28:16, 3] smbd/oplock.c:oplock_break(891) oplock_break: returning success for dev = 302, inode = 5627, file_id = 4 Current exclusive_oplocks_open = 0 [2003/06/15 23:28:16, 4] smbd/open.c:open_file_shared1(998) calling open_file with flags=0x0 flags2=0x0 mode=0744 [2003/06/15 23:28:16, 10] smbd/open.c:fd_open(51) fd_open: name Sun Contract Packet.pdf, flags = 00 mode = 0744, fd = 28. [2003/06/15 23:28:16, 2] smbd/open.c:open_file(240) JHT opened file Sun Contract Packet.pdf read=Yes write=No (numopen=1) [2003/06/15 23:28:16, 10] smbd/open.c:open_file_shared1(1100) open_file_shared : share_mode = 40 [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1562) is_in_path: match not found [2003/06/15 23:28:16, 3] smbd/oplock_linux.c:linux_set_kernel_oplock(185) linux_set_kernel_oplock: got kernel oplock on file Sun Contract Packet.pdf, dev = 302, inode = 5627, file_id = 5 [2003/06/15 23:28:16, 5] smbd/oplock.c:set_file_oplock(218) set_file_oplock: granted oplock on file Sun Contract Packet.pdf, dev = 302, inode = 5627, file_id = 5, tv_sec = 3eed5570, tv_usec = 4eb37 [2003/06/15 23:28:16, 10] locking/locking.c:set_share_mode(650) set_share_mode: creating entry for file Sun Contract Packet.pdf. num_share_modes = 1 [2003/06/15 23:28:16, 10] locking/locking.c:print_share_mode_table(402) print_share_mode_table: share_mode_entry[0]: pid = 16986, share_mode = 0x40, desired_access = 0x1, port = 0x80de, type= 0x3, file_id = 5, dev = 0x302, inode = 5627 [2003/06/15 23:28:16, 8] smbd/dosmode.c:dos_mode(122) dos_mode: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1539) is_in_path: no name list. [2003/06/15 23:28:16, 8] smbd/dosmode.c:dos_mode(168) dos_mode returning [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=65 smb_com=0x2d smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=51201 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=36865 smt_wct=15 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 9454 (0x24EE) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]=11214 (0x2BCE) smb_vwv[ 5]=16106 (0x3EEA) smb_vwv[ 6]=61610 (0xF0AA) smb_vwv[ 7]= 4 (0x4) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]=32769 (0x8001) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 0 (0x0) smb_vwv[14]= 0 (0x0) smb_bcc=0 [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,69) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,69) wrote 69 [2003/06/15 23:28:16, 10] lib/util_sock.c:read_smb_length_return_keepalive(463) got smb length of 116 [2003/06/15 23:28:16, 6] smbd/process.c:process_smb(881) got message type 0x0 of len 0x74 [2003/06/15 23:28:16, 3] smbd/process.c:process_smb(882) Transaction 13 of length 120 [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=116 smb_com=0x2d smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=32768 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=36993 smt_wct=15 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 7 (0x7) smb_vwv[ 3]= 160 (0xA0) smb_vwv[ 4]= 22 (0x16) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 3870 (0xF1E) smb_vwv[ 7]=16109 (0x3EED) smb_vwv[ 8]= 1 (0x1) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 0 (0x0) smb_vwv[14]= 0 (0x0) smb_bcc=51 [2003/06/15 23:28:16, 10] lib/util.c:dump_data(1887) [000] ED 5C 00 53 00 75 00 6E 00 20 00 43 00 6F 00 6E í\.S.u.n . .C.o.n [010] 00 74 00 72 00 61 00 63 00 74 00 20 00 50 00 61 .t.r.a.c .t. .P.a [020] 00 63 00 6B 00 65 00 74 00 2E 00 70 00 64 00 66 .c.k.e.t ...p.d.f [030] 00 00 00 ... [2003/06/15 23:28:16, 3] smbd/process.c:switch_message(676) switch message SMBopenX (pid 16986) [2003/06/15 23:28:16, 4] smbd/uid.c:change_to_user(122) change_to_user: Skipping user change - already user [2003/06/15 23:28:16, 5] smbd/filename.c:unix_convert(114) unix_convert called on file "\Sun Contract Packet.pdf" [2003/06/15 23:28:16, 3] lib/util.c:unix_clean_name(580) unix_clean_name [/Sun Contract Packet.pdf] [2003/06/15 23:28:16, 3] smbd/dosmode.c:unix_mode(110) unix_mode(Sun Contract Packet.pdf) returning 0744 [2003/06/15 23:28:16, 5] smbd/files.c:file_new(122) allocated file structure 5359, fnum = 9455 (2 used) [2003/06/15 23:28:16, 10] smbd/open.c:open_file_shared1(832) open_file_shared: fname = Sun Contract Packet.pdf, share_mode = a0, ofun = 1, mode = 744, oplock request = 3 [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1562) is_in_path: match not found [2003/06/15 23:28:16, 3] lib/util.c:unix_clean_name(580) unix_clean_name [Sun Contract Packet.pdf] [2003/06/15 23:28:16, 10] locking/locking.c:get_share_modes(446) get_share_modes: share_mode_entry[0]: pid = 16986, share_mode = 0x40, desired_access = 0x1, port = 0x80de, type= 0x3, file_id = 5, dev = 0x302, inode = 5627 [2003/06/15 23:28:16, 5] smbd/open.c:open_mode_check(618) open_mode_check: oplock_request = 3, breaking oplock (3) on file Sun Contract Packet.pdf, dev = 302, inode = 5627 [2003/06/15 23:28:16, 5] smbd/oplock.c:request_oplock_break(925) request_oplock_break: breaking our own oplock [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(526) initial_break_processing: called for dev = 302, inode = 5627 file_id = 5 Current oplocks_open (exclusive = 1, levelII = 0) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,55) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,55) wrote 55 [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(490) NT user token: (NULL) [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_root_user(218) change_to_root_user: now uid=(0,0) gid=(0,0) [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(526) initial_break_processing: called for dev = 302, inode = 5627 file_id = 5 Current oplocks_open (exclusive = 1, levelII = 0) [2003/06/15 23:28:16, 10] lib/util_sock.c:read_smb_length_return_keepalive(463) got smb length of 41 [2003/06/15 23:28:16, 6] smbd/process.c:process_smb(881) got message type 0x0 of len 0x29 [2003/06/15 23:28:16, 3] smbd/process.c:process_smb(882) Transaction 13 of length 45 [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=41 smb_com=0x4 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=32768 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=3202 smt_wct=3 smb_vwv[ 0]= 9454 (0x24EE) smb_vwv[ 1]=65535 (0xFFFF) smb_vwv[ 2]=65535 (0xFFFF) smb_bcc=0 [2003/06/15 23:28:16, 3] smbd/process.c:switch_message(676) switch message SMBclose (pid 16986) [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (500, 2147483404) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(495) NT user token of user S-1-5-21-1593769616-160655940-3590153233-2000 contains 17 SIDs SID[ 0]: S-1-5-21-1593769616-160655940-3590153233-2000 SID[ 1]: S-1-5-21-1593769616-160655940-3590153233-513 SID[ 2]: S-1-1-0 SID[ 3]: S-1-5-2 SID[ 4]: S-1-5-11 SID[ 5]: S-1-5-21-1593769616-160655940-3590153233-1011 SID[ 6]: S-1-5-21-1593769616-160655940-3590153233-1013 SID[ 7]: S-1-5-32-550 SID[ 8]: S-1-5-21-1593769616-160655940-3590153233-1029 SID[ 9]: S-1-5-21-1593769616-160655940-3590153233-1033 SID[ 10]: S-1-5-21-1593769616-160655940-3590153233-1035 SID[ 11]: S-1-5-21-1593769616-160655940-3590153233-1039 SID[ 12]: S-1-5-21-1593769616-160655940-3590153233-1041 SID[ 13]: S-1-5-21-1593769616-160655940-3590153233-1045 SID[ 14]: S-1-5-21-1593769616-160655940-3590153233-1067 SID[ 15]: S-1-5-21-1593769616-160655940-3590153233-512 SID[ 16]: S-1-5-21-1593769616-160655940-3590153233-1147 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 500 Primary group is 2147483404 and contains 13 supplementary groups Group[ 0]: 2147483404 Group[ 1]: 5 Group[ 2]: 6 Group[ 3]: 7 Group[ 4]: 14 Group[ 5]: 16 Group[ 6]: 17 Group[ 7]: 19 Group[ 8]: 20 Group[ 9]: 22 Group[ 10]: 33 Group[ 11]: 71 Group[ 12]: 73 [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_user(203) change_to_user uid=(0,500) gid=(0,2147483404) [2003/06/15 23:28:16, 3] smbd/reply.c:reply_close(2409) close fd=28 fnum=9454 (numopen=1) [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(561) del_share_entry: num_share_modes = 1 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(566) del_share_entry: deleted share_mode_entry[0]: pid = 16986, share_mode = 0x40, desired_access = 0x1, port = 0x80de, type= 0x3, file_id = 5, dev = 0x302, inode = 5627 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(574) del_share_entry: deleting entry 0 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(596) del_share_entry: Remaining table. [2003/06/15 23:28:16, 10] smbd/close.c:close_normal_file(167) close_normal_file: share_entry_count = 0 for file Sun Contract Packet.pdf [2003/06/15 23:28:16, 10] smbd/oplock_linux.c:linux_release_kernel_oplock(196) linux_release_kernel_oplock: file Sun Contract Packet.pdf, dev = 302, inode = 5627 file_id = 5 has kernel oplock state of 1. [2003/06/15 23:28:16, 10] locking/posix.c:posix_locking_close_file(1241) posix_locking_close_file: file Sun Contract Packet.pdf has no outstanding locks. [2003/06/15 23:28:16, 2] smbd/close.c:close_normal_file(228) jht closed file Sun Contract Packet.pdf (numopen=0) [2003/06/15 23:28:16, 5] smbd/files.c:file_free(357) freed files structure 9454 (1 used) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) 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=6411 smb_uid=100 smb_mid=3202 smt_wct=0 smb_bcc=0 [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,39) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,39) wrote 39 [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(526) initial_break_processing: called for dev = 302, inode = 5627 file_id = 5 Current oplocks_open (exclusive = 0, levelII = 0) [2003/06/15 23:28:16, 3] smbd/oplock.c:initial_break_processing(543) initial_break_processing: cannot find open file with dev = 302, inode = 5627 file_id = 5allowing break to succeed. [2003/06/15 23:28:16, 4] smbd/uid.c:change_to_user(122) change_to_user: Skipping user change - already user [2003/06/15 23:28:16, 3] smbd/oplock.c:oplock_break(891) oplock_break: returning success for dev = 302, inode = 5627, file_id = 5 Current exclusive_oplocks_open = 0 [2003/06/15 23:28:16, 4] smbd/open.c:open_file_shared1(998) calling open_file with flags=0x0 flags2=0x0 mode=0744 [2003/06/15 23:28:16, 10] smbd/open.c:fd_open(51) fd_open: name Sun Contract Packet.pdf, flags = 00 mode = 0744, fd = 28. [2003/06/15 23:28:16, 2] smbd/open.c:open_file(240) JHT opened file Sun Contract Packet.pdf read=Yes write=No (numopen=1) [2003/06/15 23:28:16, 10] smbd/open.c:open_file_shared1(1100) open_file_shared : share_mode = 20 [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1562) is_in_path: match not found [2003/06/15 23:28:16, 3] smbd/oplock_linux.c:linux_set_kernel_oplock(185) linux_set_kernel_oplock: got kernel oplock on file Sun Contract Packet.pdf, dev = 302, inode = 5627, file_id = 6 [2003/06/15 23:28:16, 5] smbd/oplock.c:set_file_oplock(218) set_file_oplock: granted oplock on file Sun Contract Packet.pdf, dev = 302, inode = 5627, file_id = 6, tv_sec = 3eed5570, tv_usec = 4f6a8 [2003/06/15 23:28:16, 10] locking/locking.c:set_share_mode(650) set_share_mode: creating entry for file Sun Contract Packet.pdf. num_share_modes = 1 [2003/06/15 23:28:16, 10] locking/locking.c:print_share_mode_table(402) print_share_mode_table: share_mode_entry[0]: pid = 16986, share_mode = 0x20, desired_access = 0x1, port = 0x80de, type= 0x3, file_id = 6, dev = 0x302, inode = 5627 [2003/06/15 23:28:16, 8] smbd/dosmode.c:dos_mode(122) dos_mode: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1535) is_in_path: Sun Contract Packet.pdf [2003/06/15 23:28:16, 8] lib/util.c:is_in_path(1539) is_in_path: no name list. [2003/06/15 23:28:16, 8] smbd/dosmode.c:dos_mode(168) dos_mode returning [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=65 smb_com=0x2d smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=128 smb_flg2=51201 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=36993 smt_wct=15 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 9455 (0x24EF) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]=11214 (0x2BCE) smb_vwv[ 5]=16106 (0x3EEA) smb_vwv[ 6]=61610 (0xF0AA) smb_vwv[ 7]= 4 (0x4) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]=32769 (0x8001) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 0 (0x0) smb_vwv[14]= 0 (0x0) smb_bcc=0 [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(407) write_socket(18,69) [2003/06/15 23:28:16, 6] lib/util_sock.c:write_socket(410) write_socket(18,69) wrote 69 [2003/06/15 23:28:16, 10] lib/util_sock.c:read_smb_length_return_keepalive(463) got smb length of 51 [2003/06/15 23:28:16, 6] smbd/process.c:process_smb(881) got message type 0x0 of len 0x33 [2003/06/15 23:28:16, 3] smbd/process.c:process_smb(882) Transaction 15 of length 55 [2003/06/15 23:28:16, 5] lib/util.c:show_msg(456) [2003/06/15 23:28:16, 5] lib/util.c:show_msg(466) size=51 smb_com=0x1a smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=0 smb_flg2=32768 smb_tid=1 smb_pid=6411 smb_uid=100 smb_mid=37121 smt_wct=8 smb_vwv[ 0]= 9455 (0x24EF) smb_vwv[ 1]=59711 (0xE93F) smb_vwv[ 2]= 4 (0x4) smb_vwv[ 3]= 1899 (0x76B) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_bcc=0 [2003/06/15 23:28:16, 3] smbd/process.c:switch_message(676) switch message SMBreadbraw (pid 16986) [2003/06/15 23:28:16, 4] smbd/uid.c:change_to_user(122) change_to_user: Skipping user change - already user [2003/06/15 23:28:16, 10] locking/locking.c:is_locked(78) is_locked: brl start=321855 len=1899 unlocked for file Sun Contract Packet.pdf [2003/06/15 23:28:16, 10] locking/posix.c:is_posix_locked(697) is_posix_locked: File Sun Contract Packet.pdf, offset = 321855, count = 1899, type = READ [2003/06/15 23:28:16, 10] locking/posix.c:posix_lock_in_range(633) posix_lock_in_range: offset_out = 321855, count_out = 1899 [2003/06/15 23:28:16, 8] locking/posix.c:posix_fcntl_lock(650) posix_fcntl_lock 28 12 321855 1899 0 [2003/06/15 23:28:16, 8] lib/util.c:fcntl_lock(1683) fcntl_lock 28 12 321855 1899 0 [2003/06/15 23:28:16, 8] locking/posix.c:posix_fcntl_lock(680) posix_fcntl_lock: Lock call failed [2003/06/15 23:28:16, 10] locking/locking.c:is_locked(90) is_locked: posix start=321855 len=1899 unlocked for file Sun Contract Packet.pdf [2003/06/15 23:28:16, 3] smbd/reply.c:reply_readbraw(1573) readbraw fnum=9455 start=321855 max=1899 min=0 nread=1899 [2003/06/15 23:28:16, 10] smbd/fileio.c:seek_file(47) seek_file (Sun Contract Packet.pdf): requested pos = 321855, new pos = 321855 [2003/06/15 23:28:16, 10] smbd/fileio.c:read_file(124) read_file (Sun Contract Packet.pdf): pos = 321855, size = 1899, returned 1899 [2003/06/15 23:28:16, 5] smbd/reply.c:reply_readbraw(1577) readbraw finished [2003/06/15 23:28:16, 10] lib/util_sock.c:read_socket_data(336) read_socket_data: recv of 4 returned 0. Error = Success [2003/06/15 23:28:16, 10] lib/util_sock.c:receive_smb(513) receive_smb: length < 0! [2003/06/15 23:28:16, 3] smbd/process.c:timeout_processing(1095) end of file from client [2003/06/15 23:28:16, 5] lib/gencache.c:gencache_shutdown(88) Closing cache file [2003/06/15 23:28:16, 5] libsmb/namecache.c:namecache_shutdown(79) namecache_shutdown: netbios namecache closed successfully. [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(490) NT user token: (NULL) [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_root_user(218) change_to_root_user: now uid=(0,0) gid=(0,0) [2003/06/15 23:28:16, 2] smbd/server.c:exit_server(558) Closing connections [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(490) NT user token: (NULL) [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_root_user(218) change_to_root_user: now uid=(0,0) gid=(0,0) [2003/06/15 23:28:16, 1] smbd/service.c:close_cnum(871) maggot (192.168.1.252) closed connection to service jht [2003/06/15 23:28:16, 3] smbd/connection.c:yield_connection(69) Yielding connection to jht [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(561) del_share_entry: num_share_modes = 1 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(566) del_share_entry: deleted share_mode_entry[0]: pid = 16986, share_mode = 0x20, desired_access = 0x1, port = 0x80de, type= 0x3, file_id = 6, dev = 0x302, inode = 5627 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(574) del_share_entry: deleting entry 0 [2003/06/15 23:28:16, 10] locking/locking.c:del_share_entry(596) del_share_entry: Remaining table. [2003/06/15 23:28:16, 10] smbd/close.c:close_normal_file(167) close_normal_file: share_entry_count = 0 for file Sun Contract Packet.pdf [2003/06/15 23:28:16, 10] smbd/oplock_linux.c:linux_release_kernel_oplock(196) linux_release_kernel_oplock: file Sun Contract Packet.pdf, dev = 302, inode = 5627 file_id = 6 has kernel oplock state of 1. [2003/06/15 23:28:16, 10] locking/posix.c:posix_locking_close_file(1241) posix_locking_close_file: file Sun Contract Packet.pdf has no outstanding locks. [2003/06/15 23:28:16, 2] smbd/close.c:close_normal_file(228) jht closed file Sun Contract Packet.pdf (numopen=0) [2003/06/15 23:28:16, 5] smbd/files.c:file_free(357) freed files structure 9455 (0 used) [2003/06/15 23:28:16, 3] smbd/sec_ctx.c:set_sec_ctx(288) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_nt_user_token(490) NT user token: (NULL) [2003/06/15 23:28:16, 5] auth/auth_util.c:debug_unix_user_token(509) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2003/06/15 23:28:16, 5] smbd/uid.c:change_to_root_user(218) change_to_root_user: now uid=(0,0) gid=(0,0) [2003/06/15 23:28:16, 4] smbd/vfs.c:vfs_ChDir(611) vfs_ChDir to / [2003/06/15 23:28:16, 2] smbd/utmp.c:sys_utmp_update(413) utmp_update: uname:/var/run/utmp wname:/var/log/wtmp [2003/06/15 23:28:16, 5] auth/auth_util.c:free_server_info(1168) attempting to free (and zero) a server_info structure [2003/06/15 23:28:16, 3] smbd/connection.c:yield_connection(69) Yielding connection to [2003/06/15 23:28:16, 5] smbd/oplock.c:receive_local_message(107) receive_local_message: doing select with timeout of 1 ms [2003/06/15 23:28:16, 3] smbd/server.c:exit_server(601) Server exit (normal exit)