Attachment 29 Details for Bug 173 – Debug Level 10 log of Acrobat opening a PDF file from samba share using WinMe

Debug Level 10 log of Acrobat opening a PDF file from samba share using WinMe

maggot.bad (text/plain), 104.01 KB, created by John H Terpstra (mail address dead( on 2003-06-15 22:39:51 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: John H Terpstra (mail address dead(

Created: 2003-06-15 22:39:51 UTC

Size: 104.01 KB

patch

obsolete

>[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)

Actions: View

Attachments on bug 173: 29