Attachment 4090 Details for Bug 6297 – level 3 log

level 3 log

smb.log (text/plain), 80.96 KB, created by anonymous on 2009-04-28 20:51:23 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: anonymous

Created: 2009-04-28 20:51:23 UTC

Size: 80.96 KB

patch

obsolete

>
>[2009/04/29 08:49:52,  3] smbd/oplock.c:init_oplocks(910)
>  init_oplocks: initializing messages.
>[2009/04/29 08:49:52,  3] smbd/oplock_linux.c:linux_init_kernel_oplocks(241)
>  Linux kernel oplocks enabled
>[2009/04/29 08:49:52,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (xxx.xxx.xxx.)
>[2009/04/29 08:49:52,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2009/04/29 08:49:52,  2] lib/access.c:check_access(406)
>  Allowed connection from xxx.xxx.xxx.xxx (xxx.xxx.xxx.xxx)
>[2009/04/29 08:49:52,  3] smbd/process.c:process_smb(1554)
>  Transaction 0 of length 194 (0 toread)
>[2009/04/29 08:49:52,  3] smbd/process.c:switch_message(1378)
>  switch message SMBnegprot (pid 2486) conn 0x0
>[2009/04/29 08:49:52,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:52,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [PC NETWORK PROGRAM 1.0]
>[2009/04/29 08:49:52,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [MICROSOFT NETWORKS 1.03]
>[2009/04/29 08:49:52,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [MICROSOFT NETWORKS 3.0]
>[2009/04/29 08:49:52,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [LANMAN1.0]
>[2009/04/29 08:49:52,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [LM1.2X002]
>[2009/04/29 08:49:52,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [DOS LANMAN2.1]
>[2009/04/29 08:49:52,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [LANMAN2.1]
>[2009/04/29 08:49:52,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [Samba]
>[2009/04/29 08:49:52,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [NT LANMAN 1.0]
>[2009/04/29 08:49:52,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [NT LM 0.12]
>[2009/04/29 08:49:52,  3] smbd/negprot.c:reply_nt1(392)
>  using SPNEGO
>[2009/04/29 08:49:52,  3] smbd/negprot.c:reply_negprot(674)
>  Selected protocol NT LANMAN 1.0
>[2009/04/29 08:49:55,  3] smbd/process.c:process_smb(1554)
>  Transaction 1 of length 166 (0 toread)
>[2009/04/29 08:49:55,  3] smbd/process.c:switch_message(1378)
>  switch message SMBsesssetupX (pid 2486) conn 0x0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sesssetup.c:reply_sesssetup_and_X(1412)
>  wct=12 flg2=0xc801
>[2009/04/29 08:49:55,  3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1175)
>  Doing spnego session setup
>[2009/04/29 08:49:55,  3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1210)
>  NativeOS=[Unix] NativeLanMan=[Samba] PrimaryDomain=[]
>[2009/04/29 08:49:55,  3] smbd/sesssetup.c:reply_spnego_negotiate(802)
>  reply_spnego_negotiate: Got secblob of size 47
>[2009/04/29 08:49:55,  3] libsmb/ntlmssp.c:debug_ntlmssp_flags(62)
>  Got NTLMSSP neg_flags=0x60088215
>[2009/04/29 08:49:55,  3] smbd/process.c:process_smb(1554)
>  Transaction 2 of length 264 (0 toread)
>[2009/04/29 08:49:55,  3] smbd/process.c:switch_message(1378)
>  switch message SMBsesssetupX (pid 2486) conn 0x0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sesssetup.c:reply_sesssetup_and_X(1412)
>  wct=12 flg2=0xc801
>[2009/04/29 08:49:55,  3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1175)
>  Doing spnego session setup
>[2009/04/29 08:49:55,  3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1210)
>  NativeOS=[Unix] NativeLanMan=[Samba] PrimaryDomain=[]
>[2009/04/29 08:49:55,  3] libsmb/ntlmssp.c:ntlmssp_server_auth(747)
>  Got user=[user2] domain=[WORKGROUP] workstation=[SMB] len1=24 len2=24
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] auth/auth.c:check_ntlm_password(220)
>  check_ntlm_password:  Checking password for unmapped user [WORKGROUP]\[user2]@[SMB] with the new password interface
>[2009/04/29 08:49:55,  3] auth/auth.c:check_ntlm_password(223)
>  check_ntlm_password:  mapped user is: [WORKGROUP]\[user2]@[SMB]
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] auth/auth.c:check_ntlm_password(269)
>  check_ntlm_password: sam authentication for user [user2] succeeded
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  2] auth/auth.c:check_ntlm_password(308)
>  check_ntlm_password:  authentication for user [user2] -> [user2] -> [user2] succeeded
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-21-812338376-1894962829-2885337944-3038]
>[2009/04/29 08:49:55,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-22-2-1000]
>[2009/04/29 08:49:55,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-2]
>[2009/04/29 08:49:55,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-11]
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] libsmb/ntlmssp_sign.c:ntlmssp_sign_init(337)
>  NTLMSSP Sign/Seal - Initialising with flags:
>[2009/04/29 08:49:55,  3] libsmb/ntlmssp.c:debug_ntlmssp_flags(62)
>  Got NTLMSSP neg_flags=0x60088215
>[2009/04/29 08:49:55,  3] smbd/password.c:register_existing_vuid(289)
>  register_existing_vuid: User name: user2	Real name: ,,,
>[2009/04/29 08:49:55,  3] smbd/password.c:register_existing_vuid(299)
>  register_existing_vuid: UNIX uid 1019 is UNIX user user2, and will be vuid 100
>[2009/04/29 08:49:55,  3] smbd/password.c:register_homes_share(231)
>  Adding homes service for user 'user2' using home directory: '/home/user2'
>[2009/04/29 08:49:55,  3] param/loadparm.c:lp_add_home(5902)
>  adding home's share [user2] for user 'user2' at '/home/user2'
>[2009/04/29 08:49:55,  3] smbd/process.c:process_smb(1554)
>  Transaction 3 of length 92 (0 toread)
>[2009/04/29 08:49:55,  3] smbd/process.c:switch_message(1378)
>  switch message SMBtconX (pid 2486) conn 0x0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (xxx.xxx.xxx.)
>[2009/04/29 08:49:55,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2009/04/29 08:49:55,  2] lib/access.c:check_access(406)
>  Allowed connection from xxx.xxx.xxx.xxx (xxx.xxx.xxx.xxx)
>[2009/04/29 08:49:55,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid root does not start with 'S-'.
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid nobody does not start with 'S-'.
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/service.c:make_connection_snum(871)
>  Connect path is '/tmp' for service [IPC$]
>[2009/04/29 08:49:55,  3] smbd/vfs.c:vfs_init_default(96)
>  Initialising default vfs hooks
>[2009/04/29 08:49:55,  3] smbd/vfs.c:vfs_init_custom(130)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>[2009/04/29 08:49:55,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid root does not start with 'S-'.
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid nobody does not start with 'S-'.
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (1019, 1000) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/service.c:make_connection_snum(1111)
>  smb (xxx.xxx.xxx.xxx) connect to service IPC$ initially as user user2 (uid=1019, gid=1000) (pid 2486)
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/reply.c:reply_tcon_and_X(794)
>  tconX service=IPC$ 
>[2009/04/29 08:49:55,  3] smbd/process.c:process_smb(1554)
>  Transaction 4 of length 112 (0 toread)
>[2009/04/29 08:49:55,  3] smbd/process.c:switch_message(1378)
>  switch message SMBtrans2 (pid 2486) conn 0x8068ae40
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (1019, 1000) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/msdfs.c:get_referred_path(813)
>  get_referred_path: |TEMP| in dfs path \xxx.xxx.xxx.xxx\TEMP is not a dfs root.
>[2009/04/29 08:49:55,  3] smbd/error.c:error_packet_set(61)
>  error packet at smbd/trans2.c(7299) cmd=50 (SMBtrans2) NT_STATUS_NOT_FOUND
>[2009/04/29 08:49:55,  3] smbd/process.c:process_smb(1554)
>  Transaction 5 of length 39 (0 toread)
>[2009/04/29 08:49:55,  3] smbd/process.c:switch_message(1378)
>  switch message SMBtdis (pid 2486) conn 0x8068ae40
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/service.c:close_cnum(1323)
>  smb (xxx.xxx.xxx.xxx) closed connection to service IPC$
>[2009/04/29 08:49:55,  3] smbd/connection.c:yield_connection(31)
>  Yielding connection to IPC$
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/process.c:process_smb(1554)
>  Transaction 6 of length 94 (0 toread)
>[2009/04/29 08:49:55,  3] smbd/process.c:switch_message(1378)
>  switch message SMBtconX (pid 2486) conn 0x0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (xxx.xxx.xxx.)
>[2009/04/29 08:49:55,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2009/04/29 08:49:55,  2] lib/access.c:check_access(406)
>  Allowed connection from xxx.xxx.xxx.xxx (xxx.xxx.xxx.xxx)
>[2009/04/29 08:49:55,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid root does not start with 'S-'.
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid nobody does not start with 'S-'.
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/service.c:make_connection_snum(871)
>  Connect path is '/tmp' for service [TEMP]
>[2009/04/29 08:49:55,  3] smbd/vfs.c:vfs_init_default(96)
>  Initialising default vfs hooks
>[2009/04/29 08:49:55,  3] smbd/vfs.c:vfs_init_custom(130)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>[2009/04/29 08:49:55,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid root does not start with 'S-'.
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid nobody does not start with 'S-'.
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (1019, 1000) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  1] smbd/service.c:make_connection_snum(1111)
>  smb (xxx.xxx.xxx.xxx) connect to service TEMP initially as user user2 (uid=1019, gid=1000) (pid 2486)
>[2009/04/29 08:49:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:49:55,  3] smbd/reply.c:reply_tcon_and_X(794)
>  tconX service=TEMP 
>[2009/04/29 08:50:00,  3] smbd/process.c:process_smb(1554)
>  Transaction 7 of length 57 (0 toread)
>[2009/04/29 08:50:00,  3] smbd/process.c:switch_message(1378)
>  switch message SMBecho (pid 2486) conn 0x0
>[2009/04/29 08:50:00,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:00,  3] smbd/reply.c:reply_echo(4664)
>  echo 1 times
>[2009/04/29 08:50:03,  3] smbd/process.c:process_smb(1554)
>  Transaction 8 of length 98 (0 toread)
>[2009/04/29 08:50:03,  3] smbd/process.c:switch_message(1378)
>  switch message SMBtrans2 (pid 2486) conn 0x8068ae40
>[2009/04/29 08:50:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (1019, 1000) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:03,  3] smbd/trans2.c:call_trans2qfilepathinfo(3943)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 257
>[2009/04/29 08:50:03,  3] smbd/trans2.c:call_trans2qfilepathinfo(4057)
>  call_trans2qfilepathinfo test_dir (fnum = -1) level=257 call=5 total_data=0
>[2009/04/29 08:50:08,  3] smbd/process.c:process_smb(1554)
>  Transaction 9 of length 57 (0 toread)
>[2009/04/29 08:50:08,  3] smbd/process.c:switch_message(1378)
>  switch message SMBecho (pid 2486) conn 0x0
>[2009/04/29 08:50:08,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:08,  3] smbd/reply.c:reply_echo(4664)
>  echo 1 times
>[2009/04/29 08:50:08,  3] smbd/process.c:process_smb(1554)
>  Transaction 10 of length 118 (0 toread)
>[2009/04/29 08:50:08,  3] smbd/process.c:switch_message(1378)
>  switch message SMBopenX (pid 2486) conn 0x8068ae40
>[2009/04/29 08:50:08,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (1019, 1000) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:08,  3] smbd/dosmode.c:unix_mode(124)
>  unix_mode(test_dir/test_file.txt) returning 0666
>[2009/04/29 08:50:08,  2] smbd/open.c:open_file(551)
>  user2 opened file test_dir/test_file.txt read=Yes write=Yes (numopen=1)
>[2009/04/29 08:50:08,  3] smbd/process.c:process_smb(1554)
>  Transaction 11 of length 45 (0 toread)
>[2009/04/29 08:50:08,  3] smbd/process.c:switch_message(1378)
>  switch message SMBclose (pid 2486) conn 0x8068ae40
>[2009/04/29 08:50:08,  3] smbd/reply.c:reply_close(4353)
>  close fd=26 fnum=12790 (numopen=1)
>[2009/04/29 08:50:08,  2] smbd/close.c:close_normal_file(606)
>  user2 closed file test_dir/test_file.txt (numopen=0) NT_STATUS_OK
>[2009/04/29 08:50:13,  3] smbd/process.c:process_smb(1554)
>  Transaction 12 of length 39 (0 toread)
>[2009/04/29 08:50:13,  3] smbd/process.c:switch_message(1378)
>  switch message SMBtdis (pid 2486) conn 0x8068ae40
>[2009/04/29 08:50:13,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:13,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:13,  1] smbd/service.c:close_cnum(1323)
>  smb (xxx.xxx.xxx.xxx) closed connection to service TEMP
>[2009/04/29 08:50:13,  3] smbd/connection.c:yield_connection(31)
>  Yielding connection to TEMP
>[2009/04/29 08:50:13,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:13,  3] smbd/process.c:smbd_process(1930)
>  receive_message_or_smb failed: NT_STATUS_END_OF_FILE, exiting
>[2009/04/29 08:50:13,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:13,  3] smbd/connection.c:yield_connection(31)
>  Yielding connection to 
>[2009/04/29 08:50:13,  3] smbd/server.c:exit_server_common(971)
>  Server exit (normal exit)
>
>
>[2009/04/29 08:50:37,  3] smbd/oplock.c:init_oplocks(910)
>  init_oplocks: initializing messages.
>[2009/04/29 08:50:37,  3] smbd/oplock_linux.c:linux_init_kernel_oplocks(241)
>  Linux kernel oplocks enabled
>[2009/04/29 08:50:37,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (xxx.xxx.xxx.)
>[2009/04/29 08:50:37,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2009/04/29 08:50:37,  2] lib/access.c:check_access(406)
>  Allowed connection from xxx.xxx.xxx.xxx (xxx.xxx.xxx.xxx)
>[2009/04/29 08:50:37,  3] smbd/process.c:process_smb(1554)
>  Transaction 0 of length 194 (0 toread)
>[2009/04/29 08:50:37,  3] smbd/process.c:switch_message(1378)
>  switch message SMBnegprot (pid 2502) conn 0x0
>[2009/04/29 08:50:37,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:37,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [PC NETWORK PROGRAM 1.0]
>[2009/04/29 08:50:37,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [MICROSOFT NETWORKS 1.03]
>[2009/04/29 08:50:37,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [MICROSOFT NETWORKS 3.0]
>[2009/04/29 08:50:37,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [LANMAN1.0]
>[2009/04/29 08:50:37,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [LM1.2X002]
>[2009/04/29 08:50:37,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [DOS LANMAN2.1]
>[2009/04/29 08:50:37,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [LANMAN2.1]
>[2009/04/29 08:50:37,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [Samba]
>[2009/04/29 08:50:37,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [NT LANMAN 1.0]
>[2009/04/29 08:50:37,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [NT LM 0.12]
>[2009/04/29 08:50:37,  3] smbd/negprot.c:reply_nt1(392)
>  using SPNEGO
>[2009/04/29 08:50:37,  3] smbd/negprot.c:reply_negprot(674)
>  Selected protocol NT LANMAN 1.0
>[2009/04/29 08:50:39,  3] smbd/process.c:process_smb(1554)
>  Transaction 1 of length 166 (0 toread)
>[2009/04/29 08:50:39,  3] smbd/process.c:switch_message(1378)
>  switch message SMBsesssetupX (pid 2502) conn 0x0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sesssetup.c:reply_sesssetup_and_X(1412)
>  wct=12 flg2=0xc801
>[2009/04/29 08:50:39,  3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1175)
>  Doing spnego session setup
>[2009/04/29 08:50:39,  3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1210)
>  NativeOS=[Unix] NativeLanMan=[Samba] PrimaryDomain=[]
>[2009/04/29 08:50:39,  3] smbd/sesssetup.c:reply_spnego_negotiate(802)
>  reply_spnego_negotiate: Got secblob of size 47
>[2009/04/29 08:50:39,  3] libsmb/ntlmssp.c:debug_ntlmssp_flags(62)
>  Got NTLMSSP neg_flags=0x60088215
>[2009/04/29 08:50:39,  3] smbd/process.c:process_smb(1554)
>  Transaction 2 of length 266 (0 toread)
>[2009/04/29 08:50:39,  3] smbd/process.c:switch_message(1378)
>  switch message SMBsesssetupX (pid 2502) conn 0x0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sesssetup.c:reply_sesssetup_and_X(1412)
>  wct=12 flg2=0xc801
>[2009/04/29 08:50:39,  3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1175)
>  Doing spnego session setup
>[2009/04/29 08:50:39,  3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1210)
>  NativeOS=[Unix] NativeLanMan=[Samba] PrimaryDomain=[]
>[2009/04/29 08:50:39,  3] libsmb/ntlmssp.c:ntlmssp_server_auth(747)
>  Got user=[user1] domain=[WORKGROUP] workstation=[SMB] len1=24 len2=24
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] auth/auth.c:check_ntlm_password(220)
>  check_ntlm_password:  Checking password for unmapped user [WORKGROUP]\[user1]@[SMB] with the new password interface
>[2009/04/29 08:50:39,  3] auth/auth.c:check_ntlm_password(223)
>  check_ntlm_password:  mapped user is: [WORKGROUP]\[user1]@[SMB]
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] auth/auth.c:check_ntlm_password(269)
>  check_ntlm_password: sam authentication for user [user1] succeeded
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  2] auth/auth.c:check_ntlm_password(308)
>  check_ntlm_password:  authentication for user [user1] -> [user1] -> [user1] succeeded
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-21-812338376-1894962829-2885337944-1200]
>[2009/04/29 08:50:39,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-22-2-100]
>[2009/04/29 08:50:39,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-2]
>[2009/04/29 08:50:39,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-11]
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] libsmb/ntlmssp_sign.c:ntlmssp_sign_init(337)
>  NTLMSSP Sign/Seal - Initialising with flags:
>[2009/04/29 08:50:39,  3] libsmb/ntlmssp.c:debug_ntlmssp_flags(62)
>  Got NTLMSSP neg_flags=0x60088215
>[2009/04/29 08:50:39,  3] smbd/password.c:register_existing_vuid(289)
>  register_existing_vuid: User name: user1	Real name: ,,,
>[2009/04/29 08:50:39,  3] smbd/password.c:register_existing_vuid(299)
>  register_existing_vuid: UNIX uid 100 is UNIX user user1, and will be vuid 100
>[2009/04/29 08:50:39,  3] smbd/password.c:register_homes_share(231)
>  Adding homes service for user 'user1' using home directory: '/home/user1'
>[2009/04/29 08:50:39,  3] param/loadparm.c:lp_add_home(5902)
>  adding home's share [user1] for user 'user1' at '/home/user1'
>[2009/04/29 08:50:39,  3] smbd/process.c:process_smb(1554)
>  Transaction 3 of length 92 (0 toread)
>[2009/04/29 08:50:39,  3] smbd/process.c:switch_message(1378)
>  switch message SMBtconX (pid 2502) conn 0x0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (xxx.xxx.xxx.)
>[2009/04/29 08:50:39,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2009/04/29 08:50:39,  2] lib/access.c:check_access(406)
>  Allowed connection from xxx.xxx.xxx.xxx (xxx.xxx.xxx.xxx)
>[2009/04/29 08:50:39,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid root does not start with 'S-'.
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid nobody does not start with 'S-'.
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/service.c:make_connection_snum(871)
>  Connect path is '/tmp' for service [IPC$]
>[2009/04/29 08:50:39,  3] smbd/vfs.c:vfs_init_default(96)
>  Initialising default vfs hooks
>[2009/04/29 08:50:39,  3] smbd/vfs.c:vfs_init_custom(130)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>[2009/04/29 08:50:39,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid root does not start with 'S-'.
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid nobody does not start with 'S-'.
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (100, 100) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/service.c:make_connection_snum(1111)
>  smb (xxx.xxx.xxx.xxx) connect to service IPC$ initially as user user1 (uid=100, gid=100) (pid 2502)
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/reply.c:reply_tcon_and_X(794)
>  tconX service=IPC$ 
>[2009/04/29 08:50:39,  3] smbd/process.c:process_smb(1554)
>  Transaction 4 of length 112 (0 toread)
>[2009/04/29 08:50:39,  3] smbd/process.c:switch_message(1378)
>  switch message SMBtrans2 (pid 2502) conn 0x8068ae40
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (100, 100) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/msdfs.c:get_referred_path(813)
>  get_referred_path: |TEMP| in dfs path \xxx.xxx.xxx.xxx\TEMP is not a dfs root.
>[2009/04/29 08:50:39,  3] smbd/error.c:error_packet_set(61)
>  error packet at smbd/trans2.c(7299) cmd=50 (SMBtrans2) NT_STATUS_NOT_FOUND
>[2009/04/29 08:50:39,  3] smbd/process.c:process_smb(1554)
>  Transaction 5 of length 39 (0 toread)
>[2009/04/29 08:50:39,  3] smbd/process.c:switch_message(1378)
>  switch message SMBtdis (pid 2502) conn 0x8068ae40
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/service.c:close_cnum(1323)
>  smb (xxx.xxx.xxx.xxx) closed connection to service IPC$
>[2009/04/29 08:50:39,  3] smbd/connection.c:yield_connection(31)
>  Yielding connection to IPC$
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/process.c:process_smb(1554)
>  Transaction 6 of length 94 (0 toread)
>[2009/04/29 08:50:39,  3] smbd/process.c:switch_message(1378)
>  switch message SMBtconX (pid 2502) conn 0x0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (xxx.xxx.xxx.)
>[2009/04/29 08:50:39,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2009/04/29 08:50:39,  2] lib/access.c:check_access(406)
>  Allowed connection from xxx.xxx.xxx.xxx (xxx.xxx.xxx.xxx)
>[2009/04/29 08:50:39,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid root does not start with 'S-'.
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid nobody does not start with 'S-'.
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/service.c:make_connection_snum(871)
>  Connect path is '/tmp' for service [TEMP]
>[2009/04/29 08:50:39,  3] smbd/vfs.c:vfs_init_default(96)
>  Initialising default vfs hooks
>[2009/04/29 08:50:39,  3] smbd/vfs.c:vfs_init_custom(130)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>[2009/04/29 08:50:39,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid root does not start with 'S-'.
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid nobody does not start with 'S-'.
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (100, 100) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  1] smbd/service.c:make_connection_snum(1111)
>  smb (xxx.xxx.xxx.xxx) connect to service TEMP initially as user user1 (uid=100, gid=100) (pid 2502)
>[2009/04/29 08:50:39,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:39,  3] smbd/reply.c:reply_tcon_and_X(794)
>  tconX service=TEMP 
>[2009/04/29 08:50:44,  3] smbd/process.c:process_smb(1554)
>  Transaction 7 of length 57 (0 toread)
>[2009/04/29 08:50:44,  3] smbd/process.c:switch_message(1378)
>  switch message SMBecho (pid 2502) conn 0x0
>[2009/04/29 08:50:44,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:44,  3] smbd/reply.c:reply_echo(4664)
>  echo 1 times
>[2009/04/29 08:50:47,  3] smbd/process.c:process_smb(1554)
>  Transaction 8 of length 98 (0 toread)
>[2009/04/29 08:50:47,  3] smbd/process.c:switch_message(1378)
>  switch message SMBtrans2 (pid 2502) conn 0x8068ae40
>[2009/04/29 08:50:47,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (100, 100) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:47,  3] smbd/trans2.c:call_trans2qfilepathinfo(3943)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 257
>[2009/04/29 08:50:47,  3] smbd/trans2.c:call_trans2qfilepathinfo(4057)
>  call_trans2qfilepathinfo test_dir (fnum = -1) level=257 call=5 total_data=0
>[2009/04/29 08:50:52,  3] smbd/process.c:process_smb(1554)
>  Transaction 9 of length 57 (0 toread)
>[2009/04/29 08:50:52,  3] smbd/process.c:switch_message(1378)
>  switch message SMBecho (pid 2502) conn 0x0
>[2009/04/29 08:50:52,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:52,  3] smbd/reply.c:reply_echo(4664)
>  echo 1 times
>[2009/04/29 08:50:52,  3] smbd/process.c:process_smb(1554)
>  Transaction 10 of length 132 (0 toread)
>[2009/04/29 08:50:52,  3] smbd/process.c:switch_message(1378)
>  switch message SMBtrans2 (pid 2502) conn 0x8068ae40
>[2009/04/29 08:50:52,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (100, 100) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:52,  3] smbd/trans2.c:call_trans2findfirst(1926)
>  call_trans2findfirst: dirtype = 6, maxentries = 1366, close_after_first=0, close_if_end = 1 requires_resume_key = 1 level = 0x104, max_data_bytes = 16644
>[2009/04/29 08:50:52,  3] smbd/dir.c:dptr_create(518)
>  creating new dirptr 256 for path test_dir, expect_close = 1
>[2009/04/29 08:50:52,  3] smbd/process.c:process_smb(1554)
>  Transaction 11 of length 90 (0 toread)
>[2009/04/29 08:50:52,  3] smbd/process.c:switch_message(1378)
>  switch message SMBunlink (pid 2502) conn 0x8068ae40
>[2009/04/29 08:50:52,  3] smbd/reply.c:reply_unlink(2633)
>  reply_unlink : test_dir/test_file.txt
>[2009/04/29 08:50:52,  3] smbd/dosmode.c:unix_mode(124)
>  unix_mode(test_dir/test_file.txt) returning 0666
>[2009/04/29 08:50:52,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(100, 100) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:52,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:52,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:52,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:52,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 1
>[2009/04/29 08:50:52,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/04/29 08:50:52,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:52,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (100, 100) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:52,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(100, 100) : sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:52,  3] smbd/uid.c:push_conn_ctx(388)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2009/04/29 08:50:52,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/04/29 08:50:52,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (100, 100) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:52,  3] smbd/error.c:error_packet_set(61)
>  error packet at smbd/reply.c(2643) cmd=6 (SMBunlink) NT_STATUS_ACCESS_DENIED
>[2009/04/29 08:50:55,  3] smbd/process.c:process_smb(1554)
>  Transaction 12 of length 39 (0 toread)
>[2009/04/29 08:50:55,  3] smbd/process.c:switch_message(1378)
>  switch message SMBtdis (pid 2502) conn 0x8068ae40
>[2009/04/29 08:50:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:55,  1] smbd/service.c:close_cnum(1323)
>  smb (xxx.xxx.xxx.xxx) closed connection to service TEMP
>[2009/04/29 08:50:55,  3] smbd/connection.c:yield_connection(31)
>  Yielding connection to TEMP
>[2009/04/29 08:50:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:55,  3] smbd/process.c:smbd_process(1930)
>  receive_message_or_smb failed: NT_STATUS_END_OF_FILE, exiting
>[2009/04/29 08:50:55,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/04/29 08:50:55,  3] smbd/connection.c:yield_connection(31)
>  Yielding connection to 
>[2009/04/29 08:50:55,  3] smbd/server.c:exit_server_common(971)
>  Server exit (normal exit)

Actions: View

Attachments on bug 6297: 4090 | 4107 | 4108 | 4124 | 4125 | 4172 | 4265 | 4271 | 4272 | 4273 | 4274 | 4275 | 4278